ZWave binding updates

Tags: #<Tag:0x00007f4348f70200>

Further evaluation - it seems to be quite intermittent, sometimes normal fast response, other times slow - perhaps there is also an issue with my setup and things are restarting causing the startup issue to “reappear”
Chris, I dont want to waste your time, I will keep experimenting and see whether I can find anything else.

1 Like

This is very indicative of the issue I mentioned where a device might not be responding well. It will vary if the device is at the edge of range - sometimes it will work, other times not. This leads to commands being delayed sometimes, and it also depends on what other things are happening within the network (ie it won’t happen if that device is not doing anything).

I would really suggest to check the debug logs as this should be quite easy to see.

OK I am logging and so far everything is respondong. I havent had any changes to my zwave environment other than M3 install.
Other thing of interest it seems a lot of the association groups are blank in paperui - I had this problem ages ago Im going back and resetting these. I didnt have to rediscover going from m2 to m3 did I?

If you are using the Chrome browser clear your browser cache. I have had Paper UI breakage after upgrading that was due to that.

I try to add Fibaro smart implant FGBS-222 with the Z-Wave Binding installed from Paper UI (2.4.0) but it is found as an unknown device.
How can I solve the problem?
Can you tell me step by step how to proceed with a possible update if necessary?
Thanks.

  1. Use the manual install script
  2. Delete the unknown thing from OpenHAB
  3. Rediscover the thing.

There is a manual method for installation?
I read in some post the possibility to download the latest .jar version, copy it to the openhab directory and restart openhab.
I can’t find the official download link for latest database, should I ask to @chris or do I have to register on the cd jackson website for download it?
Thanks

You did not read the README with the script that gives the manual steps. For 2.4 some additional dependencies are needed so I recommend @5iver’s script. Just installing the jar is not enough. I know, because I manually installed a newer binding on 2.5M1.

If you do a manual install them you are stuck with troubleshooting it if it fails.

The best way to install the latest version is to use the script?
When will the updated binding be released via paper UI?

Either with 2.5 Stable or you may install 2.5 Snapshot or one of the milestone releases.
2.4 Stable will never be updated to get the latest zwave database changes.

2 Likes

Hi Gents

I cannot get the smart implant to recognise under the latest snapshot 2.5 binding under OH 2.4

DB confirms its supported but I see this:

i deleted the thing and xml, no joy. There are no sensors connected but this will be used for a garage door opener as well as monitoring a reed switch for the door

Thanks

Since the last full export of the database that device seems to be missing. I already pinged Chris for that problem, you may need to be patient until the weekend.
If you have an older snapshot (before the full export) the device should be recognized, though.

Ah! That’s ok, I’m happy to wait. I just couldn’t work out what I was doing wrong. No dramas :slight_smile: thanks sihui!

2 Likes

@sihui looks like this was done - https://ci.openhab.org/job/PR-org.openhab.binding.zwave/1576/

I used build #780, created 18hrs ago but theres no change to the device

Chris found the problem on October 4 though, You need to wait for another export, perhaps this weekend.

Seems I’m not clear on how to determine when a change is added into the jar :slight_smile: Thanks Bruce

OK, I saw Chris’ PM a day ago saying he fount d the issue & added the device back.
The device is here. Note near the bottom:
image
the database & binding get pushed, usually once a week or more to here.
Note the date

Then it gets picked up by the build system here.
Date: image

1 Like

@chris I had to stop looking at this for a while but I still have massive (minutes) delays between changing an item state and the device responding. I have lost maybe 1/2 of my lifeline values in paperui things.

i will run debug logs see if I can get something useful just providing an updat eincase youve seen a cross over with anyone else with the same behavior.

Other thing different ive noticed is the aeotec gen 5 stick Im using used to strobe red, yellow, blue but now it only does that on a reboot for a while then that too seems to be in “slo mo” and stays on one color for quite a while, then the next color.

Ste you sure there are not failed zombie nodes on your network? If there are then devices try to route through them causing delays. I had strange issues caused by zombies I needed to delete from the controller. Otherwise a network heal might help.

heres a log where the behaviour was happening

12-Oct-2019 11:14:07.279 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 16: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@6485a4
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: Adding to device queue
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 16: Added 287 to queue - size 11
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 53: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 203
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
12-Oct-2019 11:14:07.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:07.289 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
12-Oct-2019 11:14:07.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
12-Oct-2019 11:14:07.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
12-Oct-2019 11:14:07.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 53: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 203
12-Oct-2019 11:14:07.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
12-Oct-2019 11:14:07.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 53: [WAIT_RESPONSE] priority=Poll, requiresResponse=true, callback: 203
12-Oct-2019 11:14:07.290 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
12-Oct-2019 11:14:07.290 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 10: sentData successfully placed on stack.
12-Oct-2019 11:14:07.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 53: Advanced to WAIT_REQUEST
12-Oct-2019 11:14:07.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 10: TID 53: Transaction not completed
12-Oct-2019 11:14:07.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
12-Oct-2019 11:14:07.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:07.334 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 22 08 32 02 21 74 00 06 7F EB 28 
12-Oct-2019 11:14:07.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=34, callback=0, payload=00 22 08 32 02 21 74 00 06 7F EB 
12-Oct-2019 11:14:07.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=34, callback=0, payload=00 22 08 32 02 21 74 00 06 7F EB 
12-Oct-2019 11:14:07.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 53: [WAIT_REQUEST] priority=Poll, requiresResponse=true, callback: 203
12-Oct-2019 11:14:07.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Application Command Request (ALIVE:DONE)
12-Oct-2019 11:14:07.334 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: resetResendCount initComplete=true isDead=false
12-Oct-2019 11:14:07.334 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: Incoming command class COMMAND_CLASS_METER, endpoint 0
12-Oct-2019 11:14:07.334 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 34: SECURITY not supported
12-Oct-2019 11:14:07.334 [DEBUG] [e.internal.protocol.commandclass.ZWaveCommandClass] - NODE 34: Received COMMAND_CLASS_METER V3 METER_REPORT
12-Oct-2019 11:14:07.334 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 34: Meter: Type=Electric(1), Scale=W(2), Value=425.963
12-Oct-2019 11:14:07.334 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 34: Got an event from Z-Wave network: ZWaveMeterValueEvent
12-Oct-2019 11:14:07.334 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 34: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=425.963
12-Oct-2019 11:14:07.334 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 34: Updating channel state zwave:device:zwavecontroller:node34:meter_watts to 425.963 [DecimalType]
12-Oct-2019 11:14:07.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Commands processed 1.
12-Oct-2019 11:14:07.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 34: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1d8ecc0.
12-Oct-2019 11:14:07.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
12-Oct-2019 11:14:07.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
12-Oct-2019 11:14:07.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
12-Oct-2019 11:14:07.335 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:09.529 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 26: ZWaveCommandClassTransactionPayload - send to node
12-Oct-2019 11:14:09.529 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 26: SECURITY NOT required on COMMAND_CLASS_METER
12-Oct-2019 11:14:09.530 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 26: Command Class COMMAND_CLASS_METER is NOT required to be secured
12-Oct-2019 11:14:09.530 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 26: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@19d6c4e
12-Oct-2019 11:14:09.530 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 26: Adding to device queue
12-Oct-2019 11:14:09.530 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 26: Added 288 to queue - size 12
12-Oct-2019 11:14:09.530 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:10.862 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 23: ZWaveCommandClassTransactionPayload - send to node
12-Oct-2019 11:14:10.863 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 23: SECURITY not supported
12-Oct-2019 11:14:10.863 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 23: Command Class COMMAND_CLASS_METER is NOT required to be secured
12-Oct-2019 11:14:10.863 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 23: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@26aee9
12-Oct-2019 11:14:10.863 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 23: Adding to device queue
12-Oct-2019 11:14:10.863 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 23: Added 289 to queue - size 13
12-Oct-2019 11:14:10.864 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:12.291 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 10: TID 53: Timeout at state WAIT_REQUEST. 3 retries remaining.
12-Oct-2019 11:14:12.291 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - Aborting Transaction!
12-Oct-2019 11:14:12.291 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 53: Transaction ABORTED
12-Oct-2019 11:14:12.291 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
12-Oct-2019 11:14:12.291 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
12-Oct-2019 11:14:12.291 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
12-Oct-2019 11:14:12.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:15.623 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Command received zwave:device:zwavecontroller:node4:switch_binary --> ON [OnOffType]
12-Oct-2019 11:14:15.623 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 4: Creating new message for application command SWITCH_BINARY_SET
12-Oct-2019 11:14:15.623 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
12-Oct-2019 11:14:15.623 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
12-Oct-2019 11:14:15.623 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
12-Oct-2019 11:14:15.623 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Added 290 to queue - size 14
12-Oct-2019 11:14:15.624 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:15.624 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling initialised at 86400 seconds - start in 1500 milliseconds.
12-Oct-2019 11:14:17.060 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Command received zwave:device:zwavecontroller:node8:switch_binary --> ON [OnOffType]
12-Oct-2019 11:14:17.060 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 8: Creating new message for application command SWITCH_BINARY_SET
12-Oct-2019 11:14:17.060 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
12-Oct-2019 11:14:17.060 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
12-Oct-2019 11:14:17.060 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue
12-Oct-2019 11:14:17.060 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 8: Added 291 to queue - size 15
12-Oct-2019 11:14:17.060 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:17.060 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling initialised at 86400 seconds - start in 1500 milliseconds.
12-Oct-2019 11:14:17.124 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling...
12-Oct-2019 11:14:18.561 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling...
12-Oct-2019 11:14:18.561 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling deferred until initialisation complete
12-Oct-2019 11:14:24.292 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 10: TID 53: Timeout at state ABORTED. 3 retries remaining.
12-Oct-2019 11:14:24.292 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 53: Transaction is current transaction, so clearing!!!!!
12-Oct-2019 11:14:24.292 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 53: Transaction CANCELLED
12-Oct-2019 11:14:24.292 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 10: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
12-Oct-2019 11:14:24.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 10: notifyTransactionResponse TID:53 CANCELLED
12-Oct-2019 11:14:24.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
12-Oct-2019 11:14:24.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 53: Transaction event listener: DONE: CANCELLED -> 
12-Oct-2019 11:14:24.293 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 04 03 25 01 FF 25 CC D3 
12-Oct-2019 11:14:24.293 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 25 01 FF 25 CC D3 
12-Oct-2019 11:14:24.294 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@59127e
12-Oct-2019 11:14:24.294 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 10: Node Init transaction completed with response COMPLETE
12-Oct-2019 11:14:24.294 [DEBUG] [protocol.initialization.ZWaveNodeInitStageAdvancer] - NODE 10: Node advancer - advancing to REQUEST_NIF
12-Oct-2019 11:14:24.294 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 10: Got an event from Z-Wave network: ZWaveInitializationStateEvent
12-Oct-2019 11:14:24.295 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 10: sendTransaction org.openhab.binding.zwave.internal.protocol.ZWaveSerialPayload@6697ed
12-Oct-2019 11:14:24.294 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
12-Oct-2019 11:14:24.299 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 290: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 204
12-Oct-2019 11:14:24.299 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 10: Adding to device queue
12-Oct-2019 11:14:24.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 10: Added 292 to queue - size 15
12-Oct-2019 11:14:24.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:26.299 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 4: TID 290: Timeout at state WAIT_RESPONSE. 3 retries remaining.
12-Oct-2019 11:14:26.299 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - Aborting Transaction!
12-Oct-2019 11:14:26.299 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 290: Transaction ABORTED
12-Oct-2019 11:14:26.299 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
12-Oct-2019 11:14:26.300 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
12-Oct-2019 11:14:26.300 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
12-Oct-2019 11:14:26.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 6: Channel zwave:device:zwavecontroller:node6:sensor_binary linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 6: Channel zwave:device:zwavecontroller:node6:sensor_relhumidity linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 6: Channel zwave:device:zwavecontroller:node6:sensor_temperature linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 6: Channel zwave:device:zwavecontroller:node6:sensor_luminance linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 6: Channel zwave:device:zwavecontroller:node6:battery-level linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Channel zwave:device:zwavecontroller:node8:switch_binary linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Channel zwave:device:zwavecontroller:node8:meter_watts linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Channel zwave:device:zwavecontroller:node8:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Channel zwave:device:zwavecontroller:node4:switch_binary linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Channel zwave:device:zwavecontroller:node4:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Channel zwave:device:zwavecontroller:node4:meter_watts linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 9: Channel zwave:device:zwavecontroller:node9:alarm_motion linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 9: Channel zwave:device:zwavecontroller:node9:sensor_luminance linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 9: Channel zwave:device:zwavecontroller:node9:sensor_temperature linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 9: Channel zwave:device:zwavecontroller:node9:battery-level linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Channel zwave:device:zwavecontroller:node7:switch_binary linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Channel zwave:device:zwavecontroller:node7:meter_watts linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 7: Channel zwave:device:zwavecontroller:node7:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 34: Channel zwave:device:zwavecontroller:node34:switch_binary linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 34: Channel zwave:device:zwavecontroller:node34:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 34: Channel zwave:device:zwavecontroller:node34:meter_watts linked - polling started.
12-Oct-2019 11:14:27.911 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 33: Channel zwave:device:zwavecontroller:node33:switch_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 31: Channel zwave:device:zwavecontroller:node31:switch_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 28: Channel zwave:device:zwavecontroller:node28:switch_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 29: Channel zwave:device:zwavecontroller:node29:switch_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 32: Channel zwave:device:zwavecontroller:node32:switch_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 22: Channel zwave:device:zwavecontroller:node22:switch_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Channel zwave:device:zwavecontroller:node30:switch_binary1 linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Channel zwave:device:zwavecontroller:node30:meter_kwh1 linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Channel zwave:device:zwavecontroller:node30:meter_watts1 linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Channel zwave:device:zwavecontroller:node30:switch_binary2 linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Channel zwave:device:zwavecontroller:node30:meter_kwh2 linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 30: Channel zwave:device:zwavecontroller:node30:meter_watts2 linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 27: Channel zwave:device:zwavecontroller:node27:switch_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 27: Channel zwave:device:zwavecontroller:node27:meter_watts linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 27: Channel zwave:device:zwavecontroller:node27:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 23: Channel zwave:device:zwavecontroller:node23:switch_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 23: Channel zwave:device:zwavecontroller:node23:meter_watts linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 23: Channel zwave:device:zwavecontroller:node23:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 26: Channel zwave:device:zwavecontroller:node26:switch_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 26: Channel zwave:device:zwavecontroller:node26:meter_watts linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 26: Channel zwave:device:zwavecontroller:node26:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Channel zwave:device:zwavecontroller:node25:sensor_binary linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Channel zwave:device:zwavecontroller:node25:sensor_relhumidity linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Channel zwave:device:zwavecontroller:node25:sensor_temperature linked - polling started.
12-Oct-2019 11:14:27.928 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Channel zwave:device:zwavecontroller:node25:sensor_luminance linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 25: Channel zwave:device:zwavecontroller:node25:battery-level linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 17: Channel zwave:device:zwavecontroller:node17:switch_binary linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 17: Channel zwave:device:zwavecontroller:node17:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 17: Channel zwave:device:zwavecontroller:node17:meter_watts linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 17: Channel zwave:device:zwavecontroller:node17:color_color linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 19: Channel zwave:device:zwavecontroller:node19:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 19: Channel zwave:device:zwavecontroller:node19:meter_watts linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 19: Channel zwave:device:zwavecontroller:node19:switch_dimmer1 linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Channel zwave:device:zwavecontroller:node13:meter_watts linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 13: Channel zwave:device:zwavecontroller:node13:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 21: Channel zwave:device:zwavecontroller:node21:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 21: Channel zwave:device:zwavecontroller:node21:meter_watts linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 21: Channel zwave:device:zwavecontroller:node21:switch_dimmer1 linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 10: Channel zwave:device:zwavecontroller:node10:switch_binary linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 10: Channel zwave:device:zwavecontroller:node10:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 10: Channel zwave:device:zwavecontroller:node10:meter_watts linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 11: Channel zwave:device:zwavecontroller:node11:switch_binary linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 11: Channel zwave:device:zwavecontroller:node11:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 11: Channel zwave:device:zwavecontroller:node11:meter_watts linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Channel zwave:device:zwavecontroller:node18:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Channel zwave:device:zwavecontroller:node18:meter_watts linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 18: Channel zwave:device:zwavecontroller:node18:switch_dimmer1 linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 20: Channel zwave:device:zwavecontroller:node20:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 20: Channel zwave:device:zwavecontroller:node20:meter_watts linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 20: Channel zwave:device:zwavecontroller:node20:switch_dimmer1 linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 15: Channel zwave:device:zwavecontroller:node15:sensor_temperature linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 15: Channel zwave:device:zwavecontroller:node15:thermostat_mode linked - polling started.
12-Oct-2019 11:14:27.920 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 33: Channel zwave:device:zwavecontroller:node33:meter_kwh linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 15: Channel zwave:device:zwavecontroller:node15:thermostat_setpoint_cooling linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 15: Channel zwave:device:zwavecontroller:node15:thermostat_setpoint_heating linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 14: Channel zwave:device:zwavecontroller:node14:switch_dimmer linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 15: Channel zwave:device:zwavecontroller:node15:thermostat_fanmode linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Channel zwave:device:zwavecontroller:node16:switch_binary1 linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Channel zwave:device:zwavecontroller:node16:meter_kwh1 linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Channel zwave:device:zwavecontroller:node16:meter_watts1 linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Channel zwave:device:zwavecontroller:node16:switch_binary2 linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Channel zwave:device:zwavecontroller:node16:meter_watts2 linked - polling started.
12-Oct-2019 11:14:27.926 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 33: Channel zwave:device:zwavecontroller:node33:color_color linked - polling started.
12-Oct-2019 11:14:27.929 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 16: Channel zwave:device:zwavecontroller:node16:meter_kwh2 linked - polling started.
12-Oct-2019 11:14:27.923 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 33: Channel zwave:device:zwavecontroller:node33:meter_watts linked - polling started.
12-Oct-2019 11:14:38.118 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Command received zwave:device:zwavecontroller:node4:switch_binary --> OFF [OnOffType]
12-Oct-2019 11:14:38.118 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 4: Creating new message for application command SWITCH_BINARY_SET
12-Oct-2019 11:14:38.118 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
12-Oct-2019 11:14:38.118 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
12-Oct-2019 11:14:38.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
12-Oct-2019 11:14:38.119 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Added 293 to queue - size 16
12-Oct-2019 11:14:38.119 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:38.119 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling initialised at 86400 seconds - start in 1500 milliseconds.
12-Oct-2019 11:14:38.299 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 4: TID 290: Timeout at state ABORTED. 3 retries remaining.
12-Oct-2019 11:14:38.299 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - TID 290: Transaction is current transaction, so clearing!!!!!
12-Oct-2019 11:14:38.299 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 290: Transaction CANCELLED
12-Oct-2019 11:14:38.300 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
12-Oct-2019 11:14:38.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: notifyTransactionResponse TID:290 CANCELLED
12-Oct-2019 11:14:38.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
12-Oct-2019 11:14:38.301 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 08 03 25 01 FF 25 CD DE 
12-Oct-2019 11:14:38.301 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 25 01 FF 25 CD DE 
12-Oct-2019 11:14:38.301 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
12-Oct-2019 11:14:38.301 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 291: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 205
12-Oct-2019 11:14:39.619 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling...
12-Oct-2019 11:14:39.620 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:zwavecontroller:node4:switch_binary
12-Oct-2019 11:14:39.620 [DEBUG] [wave.internal.converter.ZWaveBinarySwitchConverter] - NODE 4: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
12-Oct-2019 11:14:39.620 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 4: Creating new message for application command SWITCH_BINARY_GET
12-Oct-2019 11:14:39.620 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
12-Oct-2019 11:14:39.620 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
12-Oct-2019 11:14:39.620 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:zwavecontroller:node4:meter_kwh
12-Oct-2019 11:14:39.621 [DEBUG] [nding.zwave.internal.converter.ZWaveMeterConverter] - NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
12-Oct-2019 11:14:39.621 [DEBUG] [nding.zwave.internal.converter.ZWaveMeterConverter] - NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
12-Oct-2019 11:14:39.621 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 4: Creating new message for application command METER_GET
12-Oct-2019 11:14:39.621 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
12-Oct-2019 11:14:39.621 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_METER is NOT required to be secured
12-Oct-2019 11:14:39.621 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:zwavecontroller:node4:meter_watts
12-Oct-2019 11:14:39.621 [DEBUG] [nding.zwave.internal.converter.ZWaveMeterConverter] - NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
12-Oct-2019 11:14:39.621 [DEBUG] [nding.zwave.internal.converter.ZWaveMeterConverter] - NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
12-Oct-2019 11:14:39.621 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 4: Creating new message for application command METER_GET
12-Oct-2019 11:14:39.621 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
12-Oct-2019 11:14:39.621 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_METER is NOT required to be secured
12-Oct-2019 11:14:39.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
12-Oct-2019 11:14:39.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Added 294 to queue - size 16
12-Oct-2019 11:14:39.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:39.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
12-Oct-2019 11:14:39.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Added 295 to queue - size 17
12-Oct-2019 11:14:39.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:39.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
12-Oct-2019 11:14:39.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Added 296 to queue - size 18
12-Oct-2019 11:14:39.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:39.654 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Command received zwave:device:zwavecontroller:node8:switch_binary --> OFF [OnOffType]
12-Oct-2019 11:14:39.655 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 8: Creating new message for application command SWITCH_BINARY_SET
12-Oct-2019 11:14:39.656 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
12-Oct-2019 11:14:39.656 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
12-Oct-2019 11:14:39.656 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue
12-Oct-2019 11:14:39.656 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 8: Added 297 to queue - size 19
12-Oct-2019 11:14:39.656 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:39.656 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling initialised at 86400 seconds - start in 1500 milliseconds.
12-Oct-2019 11:14:40.302 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - NODE 8: TID 291: Timeout at state WAIT_RESPONSE. 3 retries remaining.
12-Oct-2019 11:14:40.302 [DEBUG] [ocol.ZWaveTransactionManager$ZWaveTransactionTimer] - Aborting Transaction!
12-Oct-2019 11:14:40.302 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 291: Transaction ABORTED
12-Oct-2019 11:14:40.302 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
12-Oct-2019 11:14:40.302 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
12-Oct-2019 11:14:40.302 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
12-Oct-2019 11:14:40.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:40.417 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Command received zwave:device:zwavecontroller:node8:switch_binary --> ON [OnOffType]
12-Oct-2019 11:14:40.417 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 8: Creating new message for application command SWITCH_BINARY_SET
12-Oct-2019 11:14:40.417 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
12-Oct-2019 11:14:40.418 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
12-Oct-2019 11:14:40.418 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue
12-Oct-2019 11:14:40.418 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 8: Added 298 to queue - size 20
12-Oct-2019 11:14:40.418 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:40.418 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling initialised at 86400 seconds - start in 1500 milliseconds.
12-Oct-2019 11:14:41.687 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Command received zwave:device:zwavecontroller:node4:switch_binary --> ON [OnOffType]
12-Oct-2019 11:14:41.688 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 4: Creating new message for application command SWITCH_BINARY_SET
12-Oct-2019 11:14:41.689 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
12-Oct-2019 11:14:41.690 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
12-Oct-2019 11:14:41.691 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
12-Oct-2019 11:14:41.692 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Added 299 to queue - size 21
12-Oct-2019 11:14:41.693 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:41.694 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling initialised at 86400 seconds - start in 1500 milliseconds.
12-Oct-2019 11:14:41.918 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling...
12-Oct-2019 11:14:41.918 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 8: Polling deferred until initialisation complete
12-Oct-2019 11:14:43.204 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling...
12-Oct-2019 11:14:43.204 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:zwavecontroller:node4:switch_binary
12-Oct-2019 11:14:43.204 [DEBUG] [wave.internal.converter.ZWaveBinarySwitchConverter] - NODE 4: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
12-Oct-2019 11:14:43.204 [DEBUG] [rotocol.commandclass.ZWaveBinarySwitchCommandClass] - NODE 4: Creating new message for application command SWITCH_BINARY_GET
12-Oct-2019 11:14:43.205 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
12-Oct-2019 11:14:43.205 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
12-Oct-2019 11:14:43.205 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:zwavecontroller:node4:meter_kwh
12-Oct-2019 11:14:43.205 [DEBUG] [nding.zwave.internal.converter.ZWaveMeterConverter] - NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
12-Oct-2019 11:14:43.205 [DEBUG] [nding.zwave.internal.converter.ZWaveMeterConverter] - NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
12-Oct-2019 11:14:43.205 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 4: Creating new message for application command METER_GET
12-Oct-2019 11:14:43.205 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
12-Oct-2019 11:14:43.205 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_METER is NOT required to be secured
12-Oct-2019 11:14:43.205 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:zwavecontroller:node4:meter_watts
12-Oct-2019 11:14:43.205 [DEBUG] [nding.zwave.internal.converter.ZWaveMeterConverter] - NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
12-Oct-2019 11:14:43.205 [DEBUG] [nding.zwave.internal.converter.ZWaveMeterConverter] - NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
12-Oct-2019 11:14:43.206 [DEBUG] [ernal.protocol.commandclass.ZWaveMeterCommandClass] - NODE 4: Creating new message for application command METER_GET
12-Oct-2019 11:14:43.206 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
12-Oct-2019 11:14:43.206 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 4: Command Class COMMAND_CLASS_METER is NOT required to be secured
12-Oct-2019 11:14:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
12-Oct-2019 11:14:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Transaction already in queue - removed original
12-Oct-2019 11:14:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Added 300 to queue - size 21
12-Oct-2019 11:14:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
12-Oct-2019 11:14:43.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Transaction already in queue - removed original
12-Oct-2019 11:14:43.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Added 301 to queue - size 21
12-Oct-2019 11:14:43.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
12-Oct-2019 11:14:43.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Adding to device queue
12-Oct-2019 11:14:43.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Transaction already in queue - removed original
12-Oct-2019 11:14:43.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 4: Added 302 to queue - size 21
12-Oct-2019 11:14:43.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.