OpenHAB won't recognise zwave dongle

unfortunately that’s not it - I copied my logback.xml over logback_debug.xml and still no zwave logging

please be aware that,differently from what is stated in several sources, for me the stick was attacched to ttyACM0 and not ttyUSB0.
I suppose you have set that parameter in openhab.cfg zwave section.

Also note that, if you have a lot of bindings jar in the addon folder it will take a lot of time for things to startup

check the other thread I opened asking for similar help (search cp210).

1 Like

Maybe we should go back to my earlier comment -:

If there’s no log, and zwave isn’t working, and you’re getting the “error sending action” error, then maybe zwave isn’t running at all…

Is the zwave JAR loaded in the addons folder, and is it the correct size (About 1.1MB I think).

Chris

many thanks for looking at this.

org.openhab.binding.zwave-1.7.1.jar is in the addons folder, and is indeed 1.1MB

I had installed using apt-get; I wonder if I should start again and install openhab manually?

(In reply to the others; not an old version of the jar file; I am using ttyACM0; not many bindings and I’m giving it plenty of time to startup)

OK - this is solved. The problem turned out to be that I am an idiot. A number of the guides online caution that if the zwave device uses tty and the openhab user does not have root privileges then they need to be added to the “dialout” group. I didn’t create an openhab user and my default user is a member of dialout - so I thought that was that. But then looking at the running processes I see that apt-get did create an openhab user, so I do need to add to the dialout group.

Everything is now behaving.

Many thanks for all your help, and apologies for wasting your time.

2 Likes

Ah - the most common problem of all :smile:

I’m glad you’re up and running - no problem about “wasting” time…

Cheers
Chris

I am actually having this same problem (feeling the idiot part as well!).

Complete beginner with OH. Running OH 1.7.1 on an RPI2 Raspbian wheezy.

I have a 1.1 M Z-Wave binding jar in my addons folder. dmesg shows my Gen5 Aeotec USB as ttyACM0, so it is loaded in my openhab config as zwave:port=/dev/ttyACM0. openhab user has been added to the dialout group.

When I go into Habmin -> Configuration -> Bindings -> Z-Wave and do pretty much anything on the Devices tab, it tells me “error sending action to the server”.

I’ve split off my zwave debug logs via logback.xml like the wiki shows, but this is all the zwave log shows me (restarted OH service as well):

2015-11-19 21:20:40.668 [DEBUG] [.zwave.internal.ZWaveActivator:36 ]- Z-Wave binding started. Version 1.7.1
2015-11-19 21:20:40.727 [DEBUG] [.z.internal.ZWaveActiveBinding:175 ]- ConverterHandler not initialised. Polling disabled.
2015-11-19 21:20:40.733 [INFO ] [.z.internal.ZWaveActiveBinding:317 ]- ZWave ‘updated’ with null config

Any advice/help would be most appreciated!!

It looks like there is no configuration set. What is in your config file?

openhab config? It has zwave:port=/dev/ttyACM0 and the heal time set for 2 AM.

Looks like I got it working. Was missing a couple things…

In openhab.cfg:
Set SUC Mode to disabled
Enabled master controller mode

In openhab startup script:
Replaced -Dgnu.io.rxtx.SerialPorts=/dev/ttyAMA0
with
-Dgnu.io.rxtx.SerialPorts=/dev/ttyACM0:/dev/ttyAMA0

Thanks!

Picaresqu3,

I am getting the same error that you have in the zwave.log file. What do you mean that you set SUC mode to disabled in the openhab.cfg file? I already have mine set to the zwave:masterController=true. Mine is apparently on /dev/ttyUSB0

Also, did you add those lines in the openhab.sh or openhab.in.sh file? There is nothing in my startup files that show the port for zwave.

Thanks,
~John

I randomly noticed the SUC mode thing on the Z-Wave Binding page in HABmin, but it wasn’t on the Z-Wave Wiki, and it isn’t listed in the openhab.cfg file/commented out like some of the other settings. After finding it in HABmin, I found more details after some searching. Here is the code:

zwave:setSUC=true

Here are some more details on what SUC mode does: https://github.com/cdjackson/HABmin/wiki/ZWave-SUC-Controller-Mode

For the startup script, I had to modify the mine in /etc/init.d/openhab. Here is the guide I had used: http://www.homeautomationforgeeks.com/openhab_start.shtml

Good luck! Let us know how you make out :smiley:

picarsqu3,

I added the code for wave:setSUC=true and it worked for a while, but the next reboot, I got the port does not exist error in the log again.

I modified the startup script using the link you provided and I am still getting the port does not exist error… I will continue to tinker with it. Thanks for the help, I do appreciate it!

~John

Sounds like that might be your startup script that needs to be adjusted. Have you tried typing dmesg just to be sure you have the right port? You can also use ls /dev/tty* to check. If you have the Aeotec stick, have you added any devices to it? Check out the little booklet that comes with it which explains about inclusion mode (and taking it out of inclusion mode when you connect it back).

Here is my config file for the hell of it:

zwave:port = /dev/ttyACM0
zwave:masterController = true
zwave:healtime = 2
zwave:softReset = false
zwave:setSUC = false

I have a Aoetec z-stick 2 with about 20 or so z-wave devices on it. It runs perfectly fine controlling the devices in Indigo 6. OpenHAB is now seeing my z-stick, the problem is, it is not seeing any devices that are already on it. I tried to set up my home.items file with the nodes that were assigned by Indigo 6… but they don’t appear to be correct. I tried commenting out all of my z-wave items in my home.items file, but that did not fix anything.

Check out the part of the Wiki that explains how to modify your logback.xml - I think you will want to put your z-wave into it’s own log and set it to Trace. This may give you some better idea of what is happening.

I have had that already set up for quite a while, but I don’t know how to change it to set it to trace, and couldn’t find an example of it… Is there a document or wiki to tell me how to interpret what this all means? Here is the 1st half from my zwave.log file:

2015-12-05 08:36:10.864 [DEBUG] [.zwave.internal.ZWaveActivator:36  ]- Z-Wave binding started. Version 1.7.1
2015-12-05 08:36:10.985 [DEBUG] [.z.internal.ZWaveActiveBinding:175 ]- ConverterHandler not initialised. Polling disabled.
2015-12-05 08:36:11.021 [INFO ] [.z.internal.ZWaveActiveBinding:325 ]- Update config, port = /dev/ttyUSB0
2015-12-05 08:36:11.021 [INFO ] [.z.internal.ZWaveActiveBinding:330 ]- Update config, healtime = 2
2015-12-05 08:36:11.028 [INFO ] [.z.internal.ZWaveActiveBinding:375 ]- Update config, setSUC = false
2015-12-05 08:36:11.029 [INFO ] [.z.internal.ZWaveActiveBinding:384 ]- Update config, softReset = false
2015-12-05 08:36:11.029 [INFO ] [.z.internal.ZWaveActiveBinding:393 ]- Update config, masterController = true
2015-12-05 08:36:11.029 [DEBUG] [.z.internal.ZWaveActiveBinding:282 ]- Initialising zwave binding
2015-12-05 08:36:11.320 [INFO ] [b.z.i.protocol.ZWaveController:146 ]- Starting Z-Wave controller
2015-12-05 08:36:11.321 [INFO ] [b.z.i.protocol.ZWaveController:154 ]- Z-Wave timeout is set to 5000ms. Soft reset is false.
2015-12-05 08:36:11.321 [INFO ] [b.z.i.protocol.ZWaveController:407 ]- Connecting to serial port /dev/ttyUSB0
2015-12-05 08:36:12.242 [DEBUG] [eController$ZWaveReceiveThread:1460]- Starting Z-Wave thread: Receive
2015-12-05 08:36:12.244 [INFO ] [b.z.i.protocol.ZWaveController:427 ]- Serial port is initialized
2015-12-05 08:36:12.245 [DEBUG] [WaveController$ZWaveSendThread:1244]- Starting Z-Wave thread: Send
2015-12-05 08:36:12.244 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Starting Z-Wave thread: Input
2015-12-05 08:36:15.249 [DEBUG] [veController$WatchDogTimerTask:176 ]- Initialising network
2015-12-05 08:36:15.274 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = GetVersion (0x15), type = Request (0x00)
2015-12-05 08:36:15.276 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:36:15.278 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 15 E9 
2015-12-05 08:36:15.279 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2015-12-05 08:36:15.276 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 0
2015-12-05 08:36:15.328 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = MemoryGetId (0x20), type = Request (0x00)
2015-12-05 08:36:15.329 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1
2015-12-05 08:36:15.352 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = SerialApiGetCapabilities (0x07), type = Request (0x00)
2015-12-05 08:36:15.354 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 2
2015-12-05 08:36:15.357 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = SerialApiSetTimeouts (0x06), type = Request (0x00)
2015-12-05 08:36:15.358 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 3
2015-12-05 08:36:15.360 [DEBUG] [i.p.s.GetSucNodeIdMessageClass:30  ]- Get SUC NodeID
2015-12-05 08:36:15.368 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = GetSucNodeId (0x56), type = Request (0x00)
2015-12-05 08:36:15.369 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 4
2015-12-05 08:36:16.097 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 4 - deferring network monitor functions.
2015-12-05 08:36:20.305 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:36:20.305 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 5
2015-12-05 08:36:20.306 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 4
2015-12-05 08:36:20.308 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 15 E9 
2015-12-05 08:36:20.309 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2015-12-05 08:36:21.118 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 4 - deferring network monitor functions.
2015-12-05 08:36:25.314 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:36:25.315 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 5
2015-12-05 08:36:25.315 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 4
2015-12-05 08:36:25.316 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 15 E9 
2015-12-05 08:36:25.317 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2015-12-05 08:36:26.120 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 4 - deferring network monitor functions.
2015-12-05 08:36:30.325 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:36:30.326 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 5
2015-12-05 08:36:30.326 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 4
2015-12-05 08:36:30.328 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 15 E9 
2015-12-05 08:36:30.330 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2015-12-05 08:36:31.122 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 4 - deferring network monitor functions.
2015-12-05 08:36:35.474 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = GetVersion (0x15), type = Request (0x00), payload = 
2015-12-05 08:36:35.475 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 3
2015-12-05 08:36:35.476 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 20 DC 
2015-12-05 08:36:35.477 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2015-12-05 08:36:36.131 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 3 - deferring network monitor functions.
2015-12-05 08:36:40.837 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:36:40.837 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 4
2015-12-05 08:36:40.837 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 3
2015-12-05 08:36:40.838 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 20 DC 
2015-12-05 08:36:40.839 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2015-12-05 08:36:41.133 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 3 - deferring network monitor functions.
2015-12-05 08:36:45.846 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:36:45.847 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 4
2015-12-05 08:36:45.847 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 3
2015-12-05 08:36:45.848 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 20 DC 
2015-12-05 08:36:45.849 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2015-12-05 08:36:46.141 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 3 - deferring network monitor functions.
2015-12-05 08:36:50.852 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:36:50.853 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 4
2015-12-05 08:36:50.854 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 3
2015-12-05 08:36:50.855 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 20 DC 
2015-12-05 08:36:50.856 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2015-12-05 08:36:51.142 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 3 - deferring network monitor functions.
2015-12-05 08:36:55.863 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = MemoryGetId (0x20), type = Request (0x00), payload = 
2015-12-05 08:36:55.864 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 2
2015-12-05 08:36:55.865 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 07 FB 
2015-12-05 08:36:55.866 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2015-12-05 08:36:56.144 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 2 - deferring network monitor functions.
2015-12-05 08:37:00.871 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:37:00.872 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 3
2015-12-05 08:37:00.874 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 2
2015-12-05 08:37:00.876 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 07 FB 
2015-12-05 08:37:00.876 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2015-12-05 08:37:01.145 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 2 - deferring network monitor functions.
2015-12-05 08:37:05.880 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:37:05.881 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 3
2015-12-05 08:37:05.883 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 2
2015-12-05 08:37:05.885 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 07 FB 
2015-12-05 08:37:05.888 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2015-12-05 08:37:06.147 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 2 - deferring network monitor functions.
2015-12-05 08:37:10.895 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:37:10.897 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 3
2015-12-05 08:37:10.898 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 2
2015-12-05 08:37:10.899 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 07 FB 
2015-12-05 08:37:10.900 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2015-12-05 08:37:11.149 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 2 - deferring network monitor functions.
2015-12-05 08:37:15.907 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = SerialApiGetCapabilities (0x07), type = Request (0x00), payload = 
2015-12-05 08:37:15.908 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 1
2015-12-05 08:37:15.909 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 05 00 06 96 0F 65 
2015-12-05 08:37:15.910 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2015-12-05 08:37:20.945 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:37:20.946 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 2
2015-12-05 08:37:20.946 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 1
2015-12-05 08:37:20.948 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 05 00 06 96 0F 65 
2015-12-05 08:37:20.948 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2015-12-05 08:37:25.952 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:37:25.953 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 2
2015-12-05 08:37:25.954 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 1
2015-12-05 08:37:25.955 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 05 00 06 96 0F 65 
2015-12-05 08:37:25.956 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2015-12-05 08:37:30.974 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:37:30.975 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 2
2015-12-05 08:37:30.976 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 1
2015-12-05 08:37:30.977 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 05 00 06 96 0F 65 
2015-12-05 08:37:30.978 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2015-12-05 08:37:35.985 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = SerialApiSetTimeouts (0x06), type = Request (0x00), payload = 96 0F

Part 2 of my zwave.log file:

2015-12-05 08:37:35.985 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:37:35.985 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 56 AA 
2015-12-05 08:37:35.986 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2015-12-05 08:37:40.996 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:37:40.997 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1
2015-12-05 08:37:40.997 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:37:40.998 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 56 AA 
2015-12-05 08:37:41.001 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2015-12-05 08:37:46.013 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:37:46.015 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1
2015-12-05 08:37:46.016 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:37:46.017 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 56 AA 
2015-12-05 08:37:46.024 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2015-12-05 08:37:51.047 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:37:51.048 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1
2015-12-05 08:37:51.048 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:37:51.049 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 56 AA 
2015-12-05 08:37:51.049 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2015-12-05 08:37:56.054 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = GetSucNodeId (0x56), type = Request (0x00), payload = 
2015-12-05 08:44:57.108 [DEBUG] [.zwave.internal.ZWaveActivator:36  ]- Z-Wave binding started. Version 1.7.1
2015-12-05 08:44:57.271 [DEBUG] [.z.internal.ZWaveActiveBinding:175 ]- ConverterHandler not initialised. Polling disabled.
2015-12-05 08:44:58.354 [INFO ] [.z.internal.ZWaveActiveBinding:325 ]- Update config, port = /dev/ttyUSB0
2015-12-05 08:44:58.355 [INFO ] [.z.internal.ZWaveActiveBinding:330 ]- Update config, healtime = 2
2015-12-05 08:44:58.356 [INFO ] [.z.internal.ZWaveActiveBinding:375 ]- Update config, setSUC = false
2015-12-05 08:44:58.392 [INFO ] [.z.internal.ZWaveActiveBinding:384 ]- Update config, softReset = false
2015-12-05 08:44:58.401 [INFO ] [.z.internal.ZWaveActiveBinding:393 ]- Update config, masterController = true
2015-12-05 08:44:58.402 [DEBUG] [.z.internal.ZWaveActiveBinding:282 ]- Initialising zwave binding
2015-12-05 08:44:58.800 [INFO ] [b.z.i.protocol.ZWaveController:146 ]- Starting Z-Wave controller
2015-12-05 08:44:58.804 [INFO ] [b.z.i.protocol.ZWaveController:154 ]- Z-Wave timeout is set to 5000ms. Soft reset is false.
2015-12-05 08:44:58.811 [INFO ] [b.z.i.protocol.ZWaveController:407 ]- Connecting to serial port /dev/ttyUSB0
2015-12-05 08:45:00.103 [DEBUG] [eController$ZWaveReceiveThread:1460]- Starting Z-Wave thread: Receive
2015-12-05 08:45:00.197 [DEBUG] [b.z.i.protocol.ZWaveController:1201]- Starting Z-Wave thread: Input
2015-12-05 08:45:00.199 [INFO ] [b.z.i.protocol.ZWaveController:427 ]- Serial port is initialized
2015-12-05 08:45:00.209 [DEBUG] [WaveController$ZWaveSendThread:1244]- Starting Z-Wave thread: Send
2015-12-05 08:45:03.214 [DEBUG] [veController$WatchDogTimerTask:176 ]- Initialising network
2015-12-05 08:45:03.260 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = GetVersion (0x15), type = Request (0x00)
2015-12-05 08:45:03.261 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:45:03.263 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 15 E9 
2015-12-05 08:45:03.265 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2015-12-05 08:45:03.270 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 0
2015-12-05 08:45:03.273 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = MemoryGetId (0x20), type = Request (0x00)
2015-12-05 08:45:03.274 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1
2015-12-05 08:45:03.280 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = SerialApiGetCapabilities (0x07), type = Request (0x00)
2015-12-05 08:45:03.288 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 2
2015-12-05 08:45:03.294 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = SerialApiSetTimeouts (0x06), type = Request (0x00)
2015-12-05 08:45:03.295 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 3
2015-12-05 08:45:03.297 [DEBUG] [i.p.s.GetSucNodeIdMessageClass:30  ]- Get SUC NodeID
2015-12-05 08:45:03.299 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = GetSucNodeId (0x56), type = Request (0x00)
2015-12-05 08:45:03.299 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 4
2015-12-05 08:45:03.478 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 4 - deferring network monitor functions.
2015-12-05 08:45:08.282 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:45:08.283 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 5
2015-12-05 08:45:08.283 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 4
2015-12-05 08:45:08.285 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 15 E9 
2015-12-05 08:45:08.286 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2015-12-05 08:45:08.479 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 4 - deferring network monitor functions.
2015-12-05 08:45:13.294 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:45:13.295 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 5
2015-12-05 08:45:13.295 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 4
2015-12-05 08:45:13.296 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 15 E9 
2015-12-05 08:45:13.297 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2015-12-05 08:45:13.484 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 4 - deferring network monitor functions.
2015-12-05 08:45:18.484 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 4 - deferring network monitor functions.
2015-12-05 08:45:18.610 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:45:18.610 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 5
2015-12-05 08:45:18.611 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 4
2015-12-05 08:45:18.611 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 15 E9 
2015-12-05 08:45:18.612 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 15 E9 
2015-12-05 08:45:23.491 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 4 - deferring network monitor functions.
2015-12-05 08:45:23.622 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = GetVersion (0x15), type = Request (0x00), payload = 
2015-12-05 08:45:23.623 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 3
2015-12-05 08:45:23.630 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 20 DC 
2015-12-05 08:45:23.641 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2015-12-05 08:45:28.492 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 3 - deferring network monitor functions.
2015-12-05 08:45:28.670 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:45:28.671 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 4
2015-12-05 08:45:28.672 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 3
2015-12-05 08:45:28.674 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 20 DC 
2015-12-05 08:45:28.685 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2015-12-05 08:45:33.499 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 3 - deferring network monitor functions.
2015-12-05 08:45:33.692 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:45:33.692 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 4
2015-12-05 08:45:33.693 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 3
2015-12-05 08:45:33.693 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 20 DC 
2015-12-05 08:45:33.694 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2015-12-05 08:45:38.501 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 3 - deferring network monitor functions.
2015-12-05 08:45:38.699 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:45:38.700 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 4
2015-12-05 08:45:38.700 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 3
2015-12-05 08:45:38.701 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 20 DC 
2015-12-05 08:45:38.701 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 20 DC 
2015-12-05 08:45:43.504 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 3 - deferring network monitor functions.
2015-12-05 08:45:43.706 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = MemoryGetId (0x20), type = Request (0x00), payload = 
2015-12-05 08:45:43.706 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 2
2015-12-05 08:45:43.707 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 07 FB 
2015-12-05 08:45:43.707 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2015-12-05 08:45:48.505 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 2 - deferring network monitor functions.
2015-12-05 08:45:48.711 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:45:48.712 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 3
2015-12-05 08:45:48.713 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 2
2015-12-05 08:45:48.714 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 07 FB 
2015-12-05 08:45:48.715 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2015-12-05 08:45:53.510 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 2 - deferring network monitor functions.
2015-12-05 08:45:53.718 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:45:53.719 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 3
2015-12-05 08:45:53.719 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 2
2015-12-05 08:45:53.720 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 07 FB 
2015-12-05 08:45:53.721 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2015-12-05 08:45:58.516 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 2 - deferring network monitor functions.
2015-12-05 08:45:58.747 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:45:58.748 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 3
2015-12-05 08:45:58.748 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 2
2015-12-05 08:45:58.749 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 07 FB 
2015-12-05 08:45:58.750 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 07 FB 
2015-12-05 08:46:03.518 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 2 - deferring network monitor functions.
2015-12-05 08:46:03.763 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = SerialApiGetCapabilities (0x07), type = Request (0x00), payload = 
2015-12-05 08:46:03.764 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 1
2015-12-05 08:46:03.764 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 05 00 06 96 0F 65 
2015-12-05 08:46:03.765 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2015-12-05 08:46:08.775 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:46:08.777 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 2
2015-12-05 08:46:08.777 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 1
2015-12-05 08:46:08.778 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 05 00 06 96 0F 65 
2015-12-05 08:46:08.778 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2015-12-05 08:46:13.791 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:46:13.791 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 2
2015-12-05 08:46:13.792 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 1
2015-12-05 08:46:13.792 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 05 00 06 96 0F 65 
2015-12-05 08:46:13.793 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2015-12-05 08:46:18.799 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:46:18.799 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 2
2015-12-05 08:46:18.800 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 1
2015-12-05 08:46:18.801 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 05 00 06 96 0F 65 
2015-12-05 08:46:18.810 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 05 00 06 96 0F 65 
2015-12-05 08:46:23.817 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = SerialApiSetTimeouts (0x06), type = Request (0x00), payload = 96 0F 
2015-12-05 08:46:23.818 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:46:23.818 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 56 AA 
2015-12-05 08:46:23.818 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2015-12-05 08:46:28.827 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2015-12-05 08:46:28.828 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1
2015-12-05 08:46:28.828 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:46:28.829 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 56 AA 
2015-12-05 08:46:28.829 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2015-12-05 08:46:33.833 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
2015-12-05 08:46:33.833 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1
2015-12-05 08:46:33.833 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:46:33.834 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 56 AA 
2015-12-05 08:46:33.834 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2015-12-05 08:46:38.842 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
2015-12-05 08:46:38.843 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1
2015-12-05 08:46:38.844 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0
2015-12-05 08:46:38.845 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 56 AA 
2015-12-05 08:46:38.846 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 255: Sending REQUEST Message = 01 03 00 56 AA 
2015-12-05 08:46:43.851 [WARN ] [WaveController$ZWaveSendThread:1365]- NODE 255: Too many retries. Discarding message: Message: class = GetSucNodeId (0x56), type = Request (0x00), payload =

[quote=“jsable, post:22, topic:2004, full:true”]
I have had that already set up for quite a while, but I don’t know how to change it to set it to trace, and couldn’t find an example of it.[/quote]

Take a look here.
And the next look should be into the comments of that example:
<!-- Change DEBUG->TRACE for even more detailed logging -->