Unable to include Z-Wave Vision PIR motion sensor

Martin_Larsson · April 25, 2017, 8:37pm

I have been trying to include my Vision Security PIR motion sensor ZP3102 for som time now. Such a long time I don’t remember all the things I have tried.

One thing I have tried is to use the latest z-wave binding 2.1 that has support for secure inclusion. Before I got some errors saying something about security but after I replaced the binding with the latest I get no errors. Instead the logs only says it cant complete the inclusion and the device is unknown.

One thing I have noticed in the logs is when I have, countless times, excluded the device it still shows up in the debug log when the controller is polling all devices. Also, I am unable to exclude/remove the device from HABmin through the advanced drop-down-menu (remove from controller). The error message is Node not found or similar. I have to use paperUI to remove it.

Here are some logs:
When setting the controller in discovery mode from HABmin:

2017-04-25 20:55:24.971 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:152b12ad:node20.
2017-04-25 20:55:24.982 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: MANUFACTURER not set
2017-04-25 20:55:24.983 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Controller status changed to ONLINE.
2017-04-25 20:55:24.984 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Controller is ONLINE. Starting device initialisation.
2017-04-25 20:55:24.988 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating node properties.
2017-04-25 20:55:24.993 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Updating node properties. MAN=2147483647
2017-04-25 20:55:24.994 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Properties synchronised
2017-04-25 20:55:24.998 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Initialising Thing Node...
2017-04-25 20:55:25.000 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Polling intialised at 1800 seconds - start in 1800000 milliseconds.

events log:

2017-04-25 20:55:24.898 [ThingAddedEvent           ] - Thing 'zwave:device:152b12ad:node20' has been added.
2017-04-25 20:55:24.966 [hingStatusInfoChangedEvent] - 'zwave:device:152b12ad:node20' changed from UNINITIALIZED to INITIALIZING
2017-04-25 20:55:24.979 [hingStatusInfoChangedEvent] - 'zwave:device:152b12ad:node20' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2017-04-25 20:55:24.990 [hingStatusInfoChangedEvent] - 'zwave:device:152b12ad:node20' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE

After added to the network:

2017-04-25 21:41:27.001 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2017-04-25 21:41:27.002 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY check internal
2017-04-25 21:41:27.004 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_SECURITY V0 SECURITY_NONCE_GET
2017-04-25 21:41:27.005 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1
2017-04-25 21:41:27.007 [DEBUG] [ommandClassTransactionPayloadBuilder] - At build null
2017-04-25 21:41:27.009 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added to secure queue - size 1
2017-04-25 21:41:27.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2017-04-25 21:41:27.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@54055f.
…
2017-04-25 21:41:27.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 187: Transaction not completed
...
2017-04-25 21:41:28.748 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 20: Device discovery could not resolve to a thingType! 7FFFFFFF:7FFFFFFF:7FFFFFFF::0.0
…
2017-04-25 21:41:28.764 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:152b12ad:node20' to inbox.

Can anybody help?

I have seen in the device database that this device is there. Is there a way to manually tell openhab what device this is? Why else is there a button to export the def. of the device from the database?

Martin_Larsson · April 27, 2017, 8:55am

More logs after reboot. Dont know if this helps or not. Can anybody see what is wrong? @chris?

2017-04-27 10:48:50.967 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:REQUEST_NIF)
2017-04-27 10:48:50.969 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2017-04-27 10:48:50.970 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Command class COMMAND_CLASS_SECURITY not found, trying to add it.
2017-04-27 10:48:50.971 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_SECURITY
2017-04-27 10:48:50.974 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE Node 20: Manufacturer=7FFFFFFF, Type=7FFFFFFF, Id=7FFFFFFF: Command class COMMAND_CLASS_SECURITY, endpoint 0 created
2017-04-27 10:48:50.975 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_SECURITY to endpoint 0
2017-04-27 10:48:50.976 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_SECURITY to the list of supported command classes.
2017-04-27 10:48:50.978 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY check internal
2017-04-27 10:48:50.979 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_SECURITY V0 SECURITY_NONCE_GET
2017-04-27 10:48:53.972 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 14 02 98 40 3D 
2017-04-27 10:48:53.977 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-04-27 10:48:53.982 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=20, callback=0, payload=00 14 02 98 40 
2017-04-27 10:48:53.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=20, callback=0, payload=00 14 02 98 40 
2017-04-27 10:49:12.855 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1
2017-04-27 10:49:12.857 [DEBUG] [ommandClassTransactionPayloadBuilder] - At build null
2017-04-27 10:49:12.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added to secure queue - size 1
2017-04-27 10:49:12.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2017-04-27 10:49:12.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@148272b.
2017-04-27 10:49:12.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-04-27 10:49:12.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-04-27 10:49:12.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], dest=20, callback=0, payload=00 14 02 98 40 
2017-04-27 10:49:12.862 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-04-27 10:49:12.862 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:REQUEST_NIF)
2017-04-27 10:49:12.863 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_SECURITY, endpoint 0
2017-04-27 10:49:12.864 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY check internal
2017-04-27 10:49:12.864 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_SECURITY V0 SECURITY_NONCE_GET
2017-04-27 10:49:12.865 [DEBUG] [andclass.impl.CommandClassSecurityV1] - Creating command message SECURITY_NONCE_REPORT version 1
2017-04-27 10:49:12.865 [DEBUG] [ommandClassTransactionPayloadBuilder] - At build null
2017-04-27 10:49:12.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added to secure queue - size 2
2017-04-27 10:49:12.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1.
2017-04-27 10:49:12.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@178e20c.
2017-04-27 10:49:12.867 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-04-27 10:49:12.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2017-04-27 10:49:12.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 10:49:12.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-04-27 10:49:12.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from secureQueue
2017-04-27 10:49:12.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
2017-04-27 10:49:12.871 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 11 00 13 14 0A 98 80 FC C9 71 A4 F9 94 74 DF 25 09 F1 
2017-04-27 10:49:12.873 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 20: Sending REQUEST Message = 01 11 00 13 14 0A 98 80 FC C9 71 A4 F9 94 74 DF 25 09 F1 
2017-04-27 10:49:12.887 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2017-04-27 10:49:12.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-04-27 10:49:12.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-04-27 10:49:12.895 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2017-04-27 10:49:12.897 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2017-04-27 10:49:12.898 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: Transaction Start type SendData 
2017-04-27 10:49:12.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 42: [WAIT_RESPONSE] requiresResponse=true callback: 9
2017-04-27 10:49:12.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
2017-04-27 10:49:12.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 0
2017-04-27 10:49:12.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
2017-04-27 10:49:12.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 10:49:14 CEST 2017 - 2000ms
2017-04-27 10:49:12.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 42: [WAIT_RESPONSE] requiresResponse=true callback: 9
2017-04-27 10:49:12.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-04-27 10:49:12.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 10:49:14 CEST 2017 - 1998ms
2017-04-27 10:49:12.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-04-27 10:49:12.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 42: [WAIT_RESPONSE] requiresResponse=true callback: 9
2017-04-27 10:49:12.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2017-04-27 10:49:12.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 10:49:12.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
2017-04-27 10:49:12.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-04-27 10:49:12.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-04-27 10:49:12.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 10:49:14 CEST 2017 - 1992ms
2017-04-27 10:49:12.917 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-04-27 10:49:12.919 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01 
2017-04-27 10:49:12.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01 
2017-04-27 10:49:12.926 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01 
2017-04-27 10:49:12.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 42: [WAIT_RESPONSE] requiresResponse=true callback: 9
2017-04-27 10:49:12.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2017-04-27 10:49:12.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 42: [WAIT_RESPONSE] requiresResponse=true callback: 9
2017-04-27 10:49:12.929 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01 
2017-04-27 10:49:12.930 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: Sent Data successfully placed on stack.
2017-04-27 10:49:12.931 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: TransactionAdvance ST: WAIT_RESPONSE
2017-04-27 10:49:12.931 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: TransactionAdvance WT: null {}
2017-04-27 10:49:12.932 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: TransactionAdvance RX: Message: class=SendData[0x13], type=Response[0x01], dest=255, callback=0, payload=01 
2017-04-27 10:49:12.933 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: TransactionAdvance TO: WAIT_REQUEST
2017-04-27 10:49:12.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 42: Advanced to WAIT_REQUEST
2017-04-27 10:49:12.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 42: Transaction not completed
2017-04-27 10:49:12.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 10:49:12.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
2017-04-27 10:49:12.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-04-27 10:49:12.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-04-27 10:49:12.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 10:49:17 CEST 2017 - 4997ms
2017-04-27 10:49:17.080 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 09 01 01 A3 41 
2017-04-27 10:49:17.085 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-04-27 10:49:17.089 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=SendData[0x13], type=Request[0x00], dest=1, callback=9, payload=09 01 01 A3 
2017-04-27 10:49:17.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=SendData[0x13], type=Request[0x00], dest=1, callback=9, payload=09 01 01 A3 
2017-04-27 10:49:17.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=SendData[0x13], type=Request[0x00], dest=1, callback=9, payload=09 01 01 A3 
2017-04-27 10:49:17.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 42: [WAIT_REQUEST] requiresResponse=true callback: 9
2017-04-27 10:49:17.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2017-04-27 10:49:17.099 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 42: [WAIT_REQUEST] requiresResponse=true callback: 9
2017-04-27 10:49:17.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 42: (Callback 9)
2017-04-27 10:49:17.101 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2017-04-27 10:49:17.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 42: callback 9
2017-04-27 10:49:17.103 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[0x13], type=Request[0x00], dest=1, callback=9, payload=09 01 01 A3 
2017-04-27 10:49:17.104 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: SendData Request. CallBack ID = 9, Status = Transmission complete, no ACK received(1)
2017-04-27 10:49:17.105 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: Transaction CANCELLED
2017-04-27 10:49:17.106 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: TransactionAdvance ST: CANCELLED
2017-04-27 10:49:17.107 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: TransactionAdvance WT: null {}
2017-04-27 10:49:17.108 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: TransactionAdvance RX: Message: class=SendData[0x13], type=Request[0x00], dest=1, callback=9, payload=09 01 01 A3 
2017-04-27 10:49:17.109 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 42: TransactionAdvance TO: CANCELLED
2017-04-27 10:49:17.110 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-04-27 10:49:17.110 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-04-27 10:49:17.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 42: Transaction completed
2017-04-27 10:49:17.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:42 CANCELLED
2017-04-27 10:49:17.113 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-04-27 10:49:17.119 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

chris · April 27, 2017, 10:01am

Please can you format the logs using the </> button so they are more readable and don’t end up with the > character on the front line. We can then use the online viewer to see what’s happening much easier.

Martin_Larsson · April 27, 2017, 10:06am

Absolutely

Martin_Larsson · April 27, 2017, 10:14am

Done @chris

chris · April 27, 2017, 10:20am

The device appears to be wanting to communicate securely - it sends a NONCE_GET, and the binding replies, and then the device doesn’t respond any more which would lead me to think the security key has changed since it was included?

Martin_Larsson · April 27, 2017, 10:23am

I have no idea how to change the security key. Is that something I can do? If so how, and to what?

What about the device still being pinged after I have removed the device from the network? Can that be it?

chris · April 27, 2017, 10:30am

It’s set in the controller configuration. If it’s changed, or you deleted and re-added the controller thing (which would change it) then it won’t communicate.

I would say it’s not removed from the network given that the device is sending data to the controller so I’m not sure what you mean by this. The binding gets the list of devices from the controller, and it will ping them, so everything points to it not be excluded.

Martin_Larsson · April 27, 2017, 10:35am

I have unsinsalled the z-wave binding to install the beta 2.1. So that might have removed the security key I guess!?

So what do I do now? Do I remove the device again from the network and re-add it?

Sorry I have might not mentioned that I have previously tried to remove the device from the network and been unable to in HABmin. Therefore been forced to remove it in PaperUI instead. After that I have seen the controller still pinging the Node 20. I would assume that the same thing will happen when I remove it again. Do you have some trick to force-remove the node?

chris · April 27, 2017, 11:00am

Yes.

Yes, you must do this - it’s the only way out.[quote=“Martin_Larsson, post:9, topic:27352”]
Therefore been forced to remove it in PaperUI instead.
[/quote]

Strange - normally PaperUI doesn’t work well for sending these sort of commands - I would really suggest to use HABmin at the moment.

No - just exclude the device. It’s clearly still included in the network and there shouldn’t be an issue if you use HABmin but you can check the logs. It must of course be done with the device close to the controller.

Martin_Larsson · April 27, 2017, 11:29am

I tried to remove the node when the device was awake and very close to the controller. In HABmin I get a green note saying the command was sent and then a yellow one saying node not found. The node is still in the list of things. This is the event log:

2017-04-27 13:26:37.624 [ThingUpdatedEvent         ] - Thing 'zwave:device:152b12ad:node20' has been updated.
2017-04-27 13:26:37.627 [ConfigStatusInfoEvent     ] - ConfigStatusInfo [configStatusMessages=[]]
2017-04-27 13:26:37.654 [BindingEvent              ] - org.openhab.binding.zwave.event.BindingEvent@1b4c722

Openhab log.

2017-04-27 13:26:37.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-04-27 13:26:37.572 [DEBUG] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
2017-04-27 13:26:37.573 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 61 14 8E 01 
2017-04-27 13:26:37.575 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 61 14 8E 01 
2017-04-27 13:26:37.579 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2017-04-27 13:26:37.581 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-04-27 13:26:37.582 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-04-27 13:26:37.584 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-04-27 13:26:37.585 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-04-27 13:26:37.585 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2017-04-27 13:26:37.585 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 61 08 93 
2017-04-27 13:26:37.586 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 13:26:37.587 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-04-27 13:26:37.587 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2017-04-27 13:26:37.588 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 199: Transaction Start type RemoveFailedNodeID 
2017-04-27 13:26:37.589 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 199: [WAIT_RESPONSE] requiresResponse=true callback: 142
2017-04-27 13:26:37.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
2017-04-27 13:26:37.591 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: null
2017-04-27 13:26:37.592 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
2017-04-27 13:26:37.594 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 13:26:39 CEST 2017 - 1999ms
2017-04-27 13:26:37.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 199: [WAIT_RESPONSE] requiresResponse=true callback: 142
2017-04-27 13:26:37.596 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-04-27 13:26:37.596 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-04-27 13:26:37.597 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 13:26:39 CEST 2017 - 1996ms
2017-04-27 13:26:37.598 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-04-27 13:26:37.599 [DEBUG] [rialmessage.IsFailedNodeMessageClass] - NODE 20: Requesting IsFailedNode status from controller.
2017-04-27 13:26:37.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 13:26:39 CEST 2017 - 1994ms
2017-04-27 13:26:37.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Adding to controller queue
2017-04-27 13:26:37.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added to queue - size 1
2017-04-27 13:26:37.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
2017-04-27 13:26:37.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-04-27 13:26:37.603 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-04-27 13:26:37.604 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 13:26:39 CEST 2017 - 1989ms
2017-04-27 13:26:37.607 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-04-27 13:26:37.610 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=RemoveFailedNodeID[0x61], type=Response[0x01], dest=255, callback=0, payload=08 
2017-04-27 13:26:37.612 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=RemoveFailedNodeID[0x61], type=Response[0x01], dest=255, callback=0, payload=08 
2017-04-27 13:26:37.618 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=RemoveFailedNodeID[0x61], type=Response[0x01], dest=255, callback=0, payload=08 
2017-04-27 13:26:37.620 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 199: [WAIT_RESPONSE] requiresResponse=true callback: 142
2017-04-27 13:26:37.621 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2017-04-27 13:26:37.623 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 199: [WAIT_RESPONSE] requiresResponse=true callback: 142
2017-04-27 13:26:37.627 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RemoveFailedNodeID[0x61], type=Response[0x01], dest=255, callback=0, payload=08 
2017-04-27 13:26:37.647 [DEBUG] [message.RemoveFailedNodeMessageClass] - Got RemoveFailedNode response.
2017-04-27 13:26:37.649 [ERROR] [message.RemoveFailedNodeMessageClass] - NODE 20: Remove failed node failed as node not found
2017-04-27 13:26:37.650 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 199: Transaction COMPLETED
2017-04-27 13:26:37.651 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveNetworkEvent
2017-04-27 13:26:37.654 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveNetworkEvent
2017-04-27 13:26:37.655 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 199: TransactionAdvance ST: DONE
2017-04-27 13:26:37.656 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 199: TransactionAdvance WT: null {}
2017-04-27 13:26:37.658 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 199: TransactionAdvance RX: Message: class=RemoveFailedNodeID[0x61], type=Response[0x01], dest=255, callback=0, payload=08 
2017-04-27 13:26:37.660 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 199: TransactionAdvance TO: DONE
2017-04-27 13:26:37.661 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 73ms
2017-04-27 13:26:37.662 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 199: Transaction completed
2017-04-27 13:26:37.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:199 DONE
2017-04-27 13:26:37.664 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

To remove it I use the Tools menu and Remove device from controller. I have not found an way to set the device in exclusion mode.

chris · April 27, 2017, 11:57am

This will not work (as you have found). This is to delete a device - not to exclude it - and the controller will not allow you to do this to devices that are still in the network. Instead you need to exclude a device properly.

The exclude option is found in the controller as it’s a controller function.

Martin_Larsson · April 27, 2017, 12:29pm

OK I found the Exclude menu item and clicked it. Woke Node 20 up and when I look in the log I see that Node 30 was removed.

Consulting the manual for the Vision motion sensor I can set the device in exclude mode and a LED is blinking as it should. Just before I clicked the Exclude devices menu item.

This is the event log:

2017-04-27 14:21:42.806 [BindingEvent              ] - org.openhab.binding.zwave.event.BindingEvent@7947b6
2017-04-27 14:21:42.841 [ThingUpdatedEvent         ] - Thing 'zwave:serial_zstick:152b12ad' has been updated.

This is the openhab log:

2017-04-27 14:21:42.425 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Controller Configuration update received
2017-04-27 14:21:42.506 [DEBUG] [zwave.handler.ZWaveControllerHandler] - Controller Configuration update controller_exclude to -232323
2017-04-27 14:21:42.508 [DEBUG] [serialmessage.RemoveNodeMessageClass] - Setting controller into EXCLUSION mode.
2017-04-27 14:21:42.509 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Adding to controller queue
2017-04-27 14:21:42.510 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added to queue - size 1
2017-04-27 14:21:42.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-04-27 14:21:42.512 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake!
2017-04-27 14:21:42.514 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Node not awake!
2017-04-27 14:21:42.516 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.518 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.519 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.521 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Node not awake!
2017-04-27 14:21:42.523 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Node not awake!
2017-04-27 14:21:42.524 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:42.525 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.527 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:42.528 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:42.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-04-27 14:21:42.531 [DEBUG] [nal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
2017-04-27 14:21:42.533 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4B 01 AF 1F 
2017-04-27 14:21:42.534 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4B 01 AF 1F 
2017-04-27 14:21:42.538 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2017-04-27 14:21:42.540 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-04-27 14:21:42.542 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-04-27 14:21:42.543 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=null[0x00], type=ACK[0x02], dest=255, callback=0, payload=
2017-04-27 14:21:42.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-04-27 14:21:42.545 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2017-04-27 14:21:42.546 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4B AF 01 00 00 1D 
2017-04-27 14:21:42.546 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 14:21:42.547 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2017-04-27 14:21:42.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-04-27 14:21:42.549 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 234: Transaction Start type RemoveNodeFromNetwork 
2017-04-27 14:21:42.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 234: [WAIT_REQUEST] requiresResponse=true callback: 175
2017-04-27 14:21:42.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
2017-04-27 14:21:42.552 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: null
2017-04-27 14:21:42.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
2017-04-27 14:21:42.555 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 14:21:47 CEST 2017 - 5000ms
2017-04-27 14:21:42.556 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 234: [WAIT_REQUEST] requiresResponse=true callback: 175
2017-04-27 14:21:42.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-04-27 14:21:42.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
2017-04-27 14:21:42.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 14:21:47 CEST 2017 - 4995ms
2017-04-27 14:21:42.593 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-04-27 14:21:42.593 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start exclusion
2017-04-27 14:21:42.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-04-27 14:21:42.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=1, callback=175, payload=AF 01 00 00 
2017-04-27 14:21:42.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Start transaction timer to Thu Apr 27 14:21:47 CEST 2017 - 4959ms
2017-04-27 14:21:42.596 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=1, callback=175, payload=AF 01 00 00 
2017-04-27 14:21:42.608 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=1, callback=175, payload=AF 01 00 00 
2017-04-27 14:21:42.613 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 234: [WAIT_REQUEST] requiresResponse=true callback: 175
2017-04-27 14:21:42.624 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2017-04-27 14:21:42.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 234: [WAIT_REQUEST] requiresResponse=true callback: 175
2017-04-27 14:21:42.642 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 234: (Callback 175)
2017-04-27 14:21:42.655 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2017-04-27 14:21:42.664 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 234: callback 175
2017-04-27 14:21:42.684 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=1, callback=175, payload=AF 01 00 00 
2017-04-27 14:21:42.694 [DEBUG] [serialmessage.RemoveNodeMessageClass] - Remove Node: Learn ready.
2017-04-27 14:21:42.699 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-04-27 14:21:42.806 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 234: Transaction COMPLETED
2017-04-27 14:21:42.813 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 234: TransactionAdvance ST: DONE
2017-04-27 14:21:42.815 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 234: TransactionAdvance WT: null {}
2017-04-27 14:21:42.819 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 234: TransactionAdvance RX: Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=1, callback=175, payload=AF 01 00 00 
2017-04-27 14:21:42.821 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 234: TransactionAdvance TO: DONE
2017-04-27 14:21:42.823 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 274ms
2017-04-27 14:21:42.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 234: Transaction completed
2017-04-27 14:21:42.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:234 DONE
2017-04-27 14:21:42.830 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-04-27 14:21:42.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 14:21:42.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-04-27 14:21:42.836 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.838 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake!
2017-04-27 14:21:42.840 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Node not awake!
2017-04-27 14:21:42.845 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.850 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.852 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.853 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.856 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Node not awake!
2017-04-27 14:21:42.860 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Node not awake!
2017-04-27 14:21:42.863 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:42.865 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:42.870 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:42.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:42.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:42.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-04-27 14:21:42.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2017-04-27 14:21:42.877 [DEBUG] [nal.protocol.ZWaveTransactionManager] - STOP transaction timer
2017-04-27 14:21:46.244 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4B AF 02 00 00 1E 
2017-04-27 14:21:46.248 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-04-27 14:21:46.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=2, callback=175, payload=AF 02 00 00 
2017-04-27 14:21:46.255 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=2, callback=175, payload=AF 02 00 00 
2017-04-27 14:21:46.258 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=2, callback=175, payload=AF 02 00 00 
2017-04-27 14:21:46.259 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-04-27 14:21:46.261 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2017-04-27 14:21:46.262 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2017-04-27 14:21:46.263 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ****************** Transaction not correlated
2017-04-27 14:21:46.265 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=2, callback=175, payload=AF 02 00 00 
2017-04-27 14:21:46.266 [DEBUG] [serialmessage.RemoveNodeMessageClass] - NODE {}: transaction not correlated for RemoveNodeMessageClass
2017-04-27 14:21:46.268 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 14:21:46.269 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-04-27 14:21:46.270 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.272 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake!
2017-04-27 14:21:46.273 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.274 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Node not awake!
2017-04-27 14:21:46.275 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.277 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.282 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.283 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Node not awake!
2017-04-27 14:21:46.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Node not awake!
2017-04-27 14:21:46.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:46.288 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.290 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:46.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.293 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:46.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-04-27 14:21:46.296 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2017-04-27 14:21:46.764 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 4B AF 03 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 9A 
2017-04-27 14:21:46.770 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-04-27 14:21:46.774 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=3, callback=175, payload=AF 03 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 
2017-04-27 14:21:46.779 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=3, callback=175, payload=AF 03 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 
2017-04-27 14:21:46.783 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=3, callback=175, payload=AF 03 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 
2017-04-27 14:21:46.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-04-27 14:21:46.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2017-04-27 14:21:46.786 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 4B AF 06 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 9F 
2017-04-27 14:21:46.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2017-04-27 14:21:46.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ****************** Transaction not correlated
2017-04-27 14:21:46.792 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=3, callback=175, payload=AF 03 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 
2017-04-27 14:21:46.794 [DEBUG] [serialmessage.RemoveNodeMessageClass] - NODE {}: transaction not correlated for RemoveNodeMessageClass
2017-04-27 14:21:46.794 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Response SENT 6
2017-04-27 14:21:46.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 14:21:46.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-04-27 14:21:46.798 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.799 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake!
2017-04-27 14:21:46.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage inputMessage: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=6, callback=175, payload=AF 06 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 
2017-04-27 14:21:46.801 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.803 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Node not awake!
2017-04-27 14:21:46.804 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage past lockMessage: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=6, callback=175, payload=AF 06 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 
2017-04-27 14:21:46.807 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.810 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.811 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.812 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Node not awake!
2017-04-27 14:21:46.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Node not awake!
2017-04-27 14:21:46.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:46.818 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.819 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:46.821 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.824 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:46.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-04-27 14:21:46.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2017-04-27 14:21:46.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=6, callback=175, payload=AF 06 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 
2017-04-27 14:21:46.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2017-04-27 14:21:46.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0
2017-04-27 14:21:46.833 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null
2017-04-27 14:21:46.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ****************** Transaction not correlated
2017-04-27 14:21:46.837 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=6, callback=175, payload=AF 06 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 
2017-04-27 14:21:46.838 [DEBUG] [serialmessage.RemoveNodeMessageClass] - NODE {}: transaction not correlated for RemoveNodeMessageClass
2017-04-27 14:21:46.840 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 14:21:46.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-04-27 14:21:46.842 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake!
2017-04-27 14:21:46.844 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Node not awake!
2017-04-27 14:21:46.847 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.849 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.851 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.854 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Node not awake!
2017-04-27 14:21:46.856 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Node not awake!
2017-04-27 14:21:46.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:46.859 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.862 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:46.863 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:21:46.864 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:21:46.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:21:46.866 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
2017-04-27 14:21:46.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
2017-04-27 14:22:12.593 [DEBUG] [ve.internal.protocol.ZWaveController] - Ending inclusion mode.
2017-04-27 14:22:12.595 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.
2017-04-27 14:22:12.597 [DEBUG] [serialmessage.RemoveNodeMessageClass] - Ending EXCLUSION mode.
2017-04-27 14:22:12.600 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Adding to controller queue
2017-04-27 14:22:12.602 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added to queue - size 1
2017-04-27 14:22:12.605 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
2017-04-27 14:22:12.607 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 17: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:22:12.609 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 17: Node not awake!
2017-04-27 14:22:12.612 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 16: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:22:12.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 16: Node not awake!
2017-04-27 14:22:12.616 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:22:12.619 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:22:12.621 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:22:12.623 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:22:12.625 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:22:12.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:22:12.630 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 27: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:22:12.632 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 27: Node not awake!
2017-04-27 14:22:12.635 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:22:12.637 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Node not awake!
2017-04-27 14:22:12.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:22:12.641 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:22:12.644 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:22:12.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?
2017-04-27 14:22:12.648 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 29: listening == false, frequentlyListening == false, awake == false
2017-04-27 14:22:12.650 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 29: Node not awake!
2017-04-27 14:22:12.653 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 30: Node not found - has this node been removed?!?

As you can see both nodes 20 and 30 are still pinged and still lists in HABmin list of Things. What should I do?

chris · April 27, 2017, 4:41pm

No - node 30 wasn’t removed - no node appears to be removed.

When you say you woke the node up - what does that mean. Normally there’s a different sequence of buttons to press to remove a device compared to wakeup

Martin_Larsson · April 27, 2017, 7:00pm

To wake up the Vision motion sensor I open the cover and a little button is released making a LED light up. This is what the manual say to wake up the device.

chris · April 27, 2017, 7:04pm

Yes, but we’re not talking about wakeup -we’re talking about exclusion. Is it the same?

Martin_Larsson · April 27, 2017, 7:05pm

No, exclusion is set by clicking a button on the back once.

chris · April 27, 2017, 7:06pm

Ok, that’s my point

Martin_Larsson · April 27, 2017, 7:08pm

In post 13, that is what I did in the second paragraph. Set the device in exclusion mode and also the controller. The logs are taken right after that.

Martin_Larsson · April 27, 2017, 7:12pm

Taken from the above log. Is this the device trying to remove itself from the network? Does not seem to take effekt?

2017-04-27 14:21:46.837 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RemoveNodeFromNetwork[0x4B], type=Request[0x00], dest=6, callback=175, payload=AF 06 00 11 04 07 01 5E 98 86 72 5A 85 59 73 80 71 31 70 84 7A 
2017-04-27 14:21:46.838 [DEBUG] [serialmessage.RemoveNodeMessageClass] - NODE {}: transaction not correlated for RemoveNodeMessageClass
2017-04-27 14:21:46.840 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2017-04-27 14:21:46.841 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start