Zwave - Unknown Device - Device Not In Database, device initialisation is not complete

I’ve tried to pair a GE Zwave fan controller model 14287. It’s immediately discovered, but I get the following error in paperUI:

I’m pretty new to this and I’m just running OH2.1 Openhabian stable release. @chris Is there a way to get this device to work?

Edit:I think this is the same issue: GE Z-Wave Plus Smart Fan Control Compatibility

Thanks

Unknown Device

This device has not been fully discovered by the binding. There are a few possible reasons for this -:
The device is not in the database. If the device attributes show that this device has a valid manufacturer ID, device ID and type, then this is likely the case (eg. you see a label like “Z-Wave node 1 (0082:6015:020D::2.0)”). Even if the device appears to be in the database, some manufacturers use multiple sets of references for different regions or versions, and your device references may not be in the database. In either case, the database must be updated and you should raise an issue to get this addressed.
The device initialisation is not complete. Once the device is included into the network, the binding must interrogate it to find out what type of device it is. One part of this process is to get the manufacturer information required to identify the device, and until this is done, the device will remain unknown. For mains powered devices, this will occur quickly, however for battery devices the device must be woken up a number of times to allow the discovery phase to complete. This must be performed with the device close to the controller.

Here’s what I see in the openhab.log file:

2017-08-27 16:10:56.484 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery could not resolve to a thingType! 7FFFFFFF:7FFFFFFF:7FFFFFFF::0.0
2017-08-27 16:10:56.490 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:15da5b25003:node5' to inbox.

and here’s from the events.log file

2017-08-27 16:10:56.492 [InboxAddedEvent           ] - Discovery Result with UID 'zwave:device:15da5b25003:node5' has been added.
2017-08-27 16:10:56.509 [BindingEvent              ] - org.openhab.binding.zwave.event.BindingEvent@15dea2e
2017-08-27 16:11:16.585 [InboxRemovedEvent         ] - Discovery Result with UID 'zwave:device:15da5b25003:node5' has been removed.
2017-08-27 16:11:16.596 [ThingAddedEvent           ] - Thing 'zwave:device:15da5b25003:node5' has been added.
2017-08-27 16:11:16.647 [hingStatusInfoChangedEvent] - 'zwave:device:15da5b25003:node5' changed from UNINITIALIZED to INITIALIZING
2017-08-27 16:11:16.650 [hingStatusInfoChangedEvent] - 'zwave:device:15da5b25003:node5' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
2017-08-27 16:11:16.654 [hingStatusInfoChangedEvent] - 'zwave:device:15da5b25003:node5' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
2017-08-27 16:11:26.438 [BindingEvent              ] - org.openhab.binding.zwave.event.BindingEvent@790112

Based on the above, the device is not really discovered - or hasn’t completed the interrogation phase at least. Either it’s not communicating with the controller, or maybe there was a problem with the inclusion process.

I would suggest to reset the device - exclude it, and then reinclude it to see if that helps for starters. Otherwise a debug log is really needed.

I don’t think so - in the other issue the device is discovered - here I don’t think it’s communicating at all and certainly not enough to get the manufacturer ID (just going on the information you’ve provided of course - maybe you have more information to conclude it’s the same?).

@chris Thanks. I’m going to figure out how to enable debug and will show the log shortly. I’ve tried to reset the device(three taps up and three taps down) and then restarted openhab service and tried to re-add the device, but still the same result.

To enable debug, log on to karaf console, then use the command -:

log:set DEBUG org.openhab.binding.zwave
1 Like

@chris Here’s the log excerpted from node5 onward.

17:02:53.908 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery could not resolve to a thingType! 7FFFFFFF:7FFFFFFF:7FFFFFFF::0.0
17:02:53.913 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:15da5b25003:node5' to inbox.
17:02:53.914 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
17:02:53.914 [INFO ] [smarthome.event.InboxAddedEvent     ] - Discovery Result with UID 'zwave:device:15da5b25003:node5' has been added.
17:02:53.919 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
17:02:53.920 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
17:02:53.920 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
17:02:53.921 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 01 70
17:02:53.922 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 01 70
17:02:53.926 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 01 00 00 B2
17:02:53.928 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:02:53.929 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 01 00 00 B2
17:02:53.931 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 01 00 00 B2
17:02:53.932 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 01 00 00
17:02:53.934 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
17:02:53.935 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
17:02:53.941 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=C1 01
17:02:53.942 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 01 00 00
17:02:53.943 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
17:02:53.944 [INFO ] [smarthome.event.BindingEvent        ] - org.openhab.binding.zwave.event.BindingEvent@1be2db1
17:02:53.944 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
17:02:53.945 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 22ms/892ms.
17:03:08.402 [INFO ] [smarthome.event.InboxRemovedEvent   ] - Discovery Result with UID 'zwave:device:15da5b25003:node5' has been removed.
17:03:08.409 [INFO ] [smarthome.event.ThingAddedEvent     ] - Thing 'zwave:device:15da5b25003:node5' has been added.
17:03:08.431 [DEBUG] [org.openhab.binding.zwave           ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.status.ConfigStatusProvider}={service.id=326, service.bundleid=185, service.scope=singleton} - org.openhab.binding.zwave
17:03:08.437 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:device:15da5b25003:node5' changed from UNINITIALIZED to INITIALIZING
17:03:08.439 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler.
17:03:08.446 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: MANUFACTURER not set
17:03:08.447 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller status changed to ONLINE.
17:03:08.448 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller is ONLINE. Starting device initialisation.
17:03:08.451 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating node properties.
17:03:08.452 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:device:15da5b25003:node5' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline
17:03:08.452 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating node properties. MAN=2147483647
17:03:08.456 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Initialising Thing Node...
17:03:08.457 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Polling intialised at 1800 seconds - start in 1800000 milliseconds.
17:03:08.459 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:device:15da5b25003:node5' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE
17:03:22.289 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.
17:03:22.290 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
17:03:22.291 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
17:03:22.292 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller end inclusion
17:03:22.292 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
17:03:22.293 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 01 B4
17:03:22.294 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 01 B4
17:03:22.348 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 06 05 00 B0
17:03:22.350 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:03:22.352 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 06 05 00 B0
17:03:22.354 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 06 05 00 B0
17:03:22.355 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 05 00
17:03:22.357 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
17:03:22.358 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
17:03:22.365 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovered
17:03:22.368 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveInclusionEvent
17:03:22.369 [INFO ] [smarthome.event.BindingEvent        ] - org.openhab.binding.zwave.event.BindingEvent@1986c2c
17:03:22.371 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 5: Newly included node already initialising at PROTOINFO
17:03:22.372 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=05 01
17:03:22.374 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 05 00
17:03:22.376 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
17:03:22.377 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
17:03:22.378 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 83ms/892ms.

The log doesn’t contain any communications with the device so there’s not much I can comment on about why it’s not discovered -:

image

I would suggest to get a longer log and looking at the log viewer yourself to see if there’s communication with node 5. If there is I’m happy to check it out…

(when pasting in a log, please format with the </> button as it’s much easier to read).

@chris Can you suggest some ways to elicit some communication with the device? I have the log open and the switch is able to control the fan, but I’m not seeing anything in the log.

Is it reasonably close to the controller? Let’s say 20 to 30 feet max? If so, I suspect that it needs to be excluded and then included again - I know you’ve done that, but I’d suggest to do it again, and get the full debug log so we can see what’s going on (if anything).

If there’s still nothing happening, then I’m not sure - if it excludes and reincludes again then it should be able to communicate…

@chris Yes, less than 15ft from the controller. I’ve tried to exclude and re-include and get the same results. Now, I am getting two nodes found. Both have the same ID:

Time	Node	Entry
20:20:18.889	2	
THING TYPE zwave:ge_12718_00_000
20:20:19.043	3	
THING TYPE zwave:homeseer_hswd100_00_000
20:20:19.231	4	
THING TYPE zwave:homeseer_hswd100_00_000
20:20:19.716		
TX REQ AddNodeToNetwork ADD_NODE_ANY HIGH POWER NWI
20:20:24.717		
Message retry (2 attempts remaining)
20:20:24.729		
TX REQ AddNodeToNetwork ADD_NODE_ANY HIGH POWER NWI
20:20:29.730		
Message retry (1 attempts remaining)
20:20:29.747		
TX REQ AddNodeToNetwork ADD_NODE_ANY HIGH POWER NWI
20:20:34.749		
Message retry (0 attempts remaining)
20:20:34.758		
TX REQ AddNodeToNetwork ADD_NODE_ANY HIGH POWER NWI
20:20:48.752		
TX REQ AddNodeToNetwork ADD_NODE_STOP
20:20:53.754		
Message retry (2 attempts remaining)
20:20:53.765		
TX REQ AddNodeToNetwork ADD_NODE_STOP
20:20:58.767		
Message retry (1 attempts remaining)
20:20:58.795		
TX REQ AddNodeToNetwork ADD_NODE_STOP
20:21:03.802		
Message retry (0 attempts remaining)
20:21:03.825		
TX REQ AddNodeToNetwork ADD_NODE_STOP
20:21:08.849		
TX REQ RemoveFailedNodeID
20:21:13.853		
Message retry (2 attempts remaining)
20:21:13.863		
TX REQ RemoveFailedNodeID
20:21:18.865		
Message retry (1 attempts remaining)
20:21:18.875		
TX REQ RemoveFailedNodeID
20:21:23.879		
Message retry (0 attempts remaining)
20:21:23.882		
TX REQ RemoveFailedNodeID

I’m not sure what you mean by this - one is node ID 5 and the other node ID 6…

Does this mean that it was not properly excluded from the controller?

Chad, what zwave controller are you using?

I think you may be right. I think they are not being excluded properly. They immediately show up when I try to search for devices. I have an Aeotek Z Stick Gen 5. I’m going to try a full reset of the stick this evening and try again.

For that stick, it’s a long press of the button and wait for the Yellow light to flash. Then actuate the switch. Here are the instructions from the Aeon site.

Removing devices via its button (Example).

  1. Remove the Z-Stick from your PC USB port (disconnect)
  2. Press and hold the Z-Stick Gen5 button until its LED should blinks yellow rapidly, then release its button.
  3. Now go to your Switch or Z-Wave device, and tap its button quickly (most devices use a single tap, but some Z-Wave devices may have special operations to unpair, please refer to your Z-Wave devices manual to unpair it from the Z-Stick).
  4. If successful, you’ll notice that the Z-Stick Gen5s LED will become solid blue for 2 seconds to indicate a successful unpair, then it will return to blinking yellow rapidly to allow you to continue removing other devices.
  5. You can continue to remove devices while it is blinking yellow rapidly (follow steps 3-4 with each device you want to remove then continue to step 6 when you are done)
  6. Once you are done, tap the Z-Sticks button once and the LED should stop blinking.

At this time you don’t have a lot of devices so a reset isn’t a massive exercise. You can take backups of your stick. Download the Backup Software from Aeon here.

1 Like

I manually removed the bogus inclusions/devices via this:
http://docs.incontrolha.com/home/remove-a-failed-device via [SOLVED] How to delete a dead node from a zwave stick

Then I tried to add the device again via paperUI and still the old “unknown” devices keep showing up the in the list. So, I switched back to Zensys and manually included the fan control there. I was able to turn the fan on and off via Zensys.

Anyway, I reboot the pi, and got everything all cleared up.

Now, I started paperUI and tried to discover the switch by searching and pressing a button on the switch. Here’s my log @chris :

17:52:32.294 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Stage PROTOINFO. Initialisation retry timer triggered. Increased to 160000
17:52:32.295 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - PROTOINFO: queue length(0), free to send(false)
17:52:32.296 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Initialisation retry timer started 160000
17:52:32.296 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - PROTOINFO try 5: stageAdvanced(false)
17:52:32.297 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: PROTOINFO - send IdentifyNode
17:52:32.298 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - queued packet. Queue length is 1
17:52:32.299 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
17:52:32.299 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
17:52:32.300 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 09 B3
17:52:32.302 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 09 B3
17:52:32.310 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:32.313 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:52:32.314 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:32.315 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:32.316 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=IdentifyNode[0x41], type=Response[0x01], priority=High, dest=255, callback=0, payload=D3 9C 01 04 11 08
17:52:32.317 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: ProtocolInfo
17:52:32.318 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Listening = true
17:52:32.318 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Routing = true
17:52:32.319 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Beaming = true
17:52:32.320 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Version = 4
17:52:32.320 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: FLIRS = false
17:52:32.321 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Security = false
17:52:32.321 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Max Baud = 40000
17:52:32.322 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Basic = Routing Slave
17:52:32.323 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Generic = Multi-Level Switch
17:52:32.323 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Specific device class 8 not found
17:52:37.304 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
17:52:37.305 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
17:52:37.306 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
17:52:37.307 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 09 B3
17:52:37.309 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 09 B3
17:52:37.316 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:37.318 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:52:37.319 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:37.320 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:37.321 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=IdentifyNode[0x41], type=Response[0x01], priority=High, dest=255, callback=0, payload=D3 9C 01 04 11 08
17:52:37.322 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: ProtocolInfo
17:52:37.323 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Listening = true
17:52:37.324 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Routing = true
17:52:37.324 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Beaming = true
17:52:37.325 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Version = 4
17:52:37.325 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: FLIRS = false
17:52:37.326 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Security = false
17:52:37.326 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Max Baud = 40000
17:52:37.327 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Basic = Routing Slave
17:52:37.328 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Generic = Multi-Level Switch
17:52:37.328 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Specific device class 8 not found
17:52:42.310 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
17:52:42.311 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
17:52:42.311 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
17:52:42.312 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 09 B3
17:52:42.314 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 09 B3
17:52:42.322 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:42.324 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:52:42.325 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:42.326 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:42.327 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=IdentifyNode[0x41], type=Response[0x01], priority=High, dest=255, callback=0, payload=D3 9C 01 04 11 08
17:52:42.328 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: ProtocolInfo
17:52:42.329 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Listening = true
17:52:42.330 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Routing = true
17:52:42.330 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Beaming = true
17:52:42.331 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Version = 4
17:52:42.331 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: FLIRS = false
17:52:42.332 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Security = false
17:52:42.332 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Max Baud = 40000
17:52:42.333 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Basic = Routing Slave
17:52:42.334 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Generic = Multi-Level Switch
17:52:42.334 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Specific device class 8 not found
17:52:47.315 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
17:52:47.316 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
17:52:47.321 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
17:52:47.322 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 09 B3
17:52:47.323 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 09 B3
17:52:47.330 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:47.332 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:52:47.333 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:47.334 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 01 41 D3 9C 01 04 11 08 E5
17:52:47.335 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=IdentifyNode[0x41], type=Response[0x01], priority=High, dest=255, callback=0, payload=D3 9C 01 04 11 08
17:52:47.336 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: ProtocolInfo
17:52:47.336 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Listening = true
17:52:47.337 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Routing = true
17:52:47.338 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Beaming = true
17:52:47.338 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Version = 4
17:52:47.339 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: FLIRS = false
17:52:47.339 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Security = false
17:52:47.340 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Max Baud = 40000
17:52:47.340 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Basic = Routing Slave
17:52:47.341 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Generic = Multi-Level Switch
17:52:47.341 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Specific device class 8 not found
17:52:52.325 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Too many retries. Discarding message: Message: class=IdentifyNode[0x41], type=Request[0x00], priority=High, dest=255, callback=0, payload=09

Any ideas?

Yes - I see the problem and I’ll take a look at this tonight.

1 Like

@chris Any luck with this issue? Should I submit an issue on github? Thanks.

I made the changes that I think should have solved it a few days ago. I guess it didn’t work? :frowning: .

@chris how do I get the updates? I haven’t tried anything yet.

Ah - ok, maybe there’s still hope :slight_smile: .

You need to update to the latest snapshot version of the binding. If you’re running snapshot runtime, then just go to PaperUI and uninstall the binding, wait 30 seconds and reinstall it again.

getting a little side-tracked:
I’ve attempted to upgrade from 2.1 stable to 2.2 snapshot. It looks like the I have the 2.2 zwave snapshot binding installed after the upgrade. No errors during the upgrade. I have some UI errors and also can’t seem to get to device discovery in paperUI:


Nothing happens after I select the Zwave binding

HABmin:
I tried in HABmin and the items list is empty. I receive an error when I click/switch from the things menu to the bindings menu. Switching to any other menu, I don’t get the error message. Also, the binding menu never populates with bindings :