Philio PST02C with OH2: door contact does not work?!

Sorry - I missed the fact that you’d posted a new open/close log at the end. I thought this was all the inclusion log in multiple parts.

No problem - I thought that this didn’t get clear… :slight_smile:
Many thanks for your support… again.

Hopefully the next update will fix it - at the moment it only reports OPEN…

Ok, I’ll try it with new snapshot tomorrow.

Tested it with the snapshot from 15.10.2016 20:27:
The “sensor_binary” now shows correct behavior: ON for open door, OFF for closed door.
But “sensor_door” still not working.

Was your change already included in this build?

Yes - it should be included. What does it show - only OPEN still?

Yes, it shows always “OPEN”

Strange - I added some tests that correctly decode this. Can you provide another log of the open/close please? (sorry - yet more logs ;)).

If we (or better you) can come a step further, I’ll of course provide more logs :wink:

First open, then close again:

2016-10-15 21:44:32.221 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 20 01 FF 26 
2016-10-15 21:44:32.237 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-15 21:44:32.246 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 20 01 FF 26 
2016-10-15 21:44:32.250 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 20 01 FF 26 
2016-10-15 21:44:32.254 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 03 20 01 FF 
2016-10-15 21:44:32.257 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-15 21:44:32.259 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-15 21:44:32.262 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@24ab2d already registered
2016-10-15 21:44:32.265 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2016-10-15 21:44:32.267 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2016-10-15 21:44:32.269 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2016-10-15 21:44:32.271 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0xFF
2016-10-15 21:44:32.273 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-15 21:44:32.274 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-15 21:44:32.276 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
2016-10-15 21:44:32.278 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_binary to ON [OnOffType]
2016-10-15 21:44:32.285 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 10: Transaction not completed: node address inconsistent.  lastSent=10, incoming=255
2016-10-15 21:44:32.318 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 24 00 04 00 09 1E 8F 01 04 03 80 03 64 09 71 05 00 00 00 FF 06 16 00 05 31 05 03 01 08 06 31 05 01 22 01 13 0C 
2016-10-15 21:44:32.329 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-15 21:44:32.334 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 24 00 04 00 09 1E 8F 01 04 03 80 03 64 09 71 05 00 00 00 FF 06 16 00 05 31 05 03 01 08 06 31 05 01 22 01 13 0C 
2016-10-15 21:44:32.338 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 24 00 04 00 09 1E 8F 01 04 03 80 03 64 09 71 05 00 00 00 FF 06 16 00 05 31 05 03 01 08 06 31 05 01 22 01 13 0C 
2016-10-15 21:44:32.345 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 1E 8F 01 04 03 80 03 64 09 71 05 00 00 00 FF 06 16 00 05 31 05 03 01 08 06 31 05 01 22 01 13 
2016-10-15 21:44:32.346 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-15 21:44:32.348 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-15 21:44:32.351 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@24ab2d already registered
2016-10-15 21:44:32.352 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-15 21:44:32.353 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-15 21:44:32.354 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class BATTERY
2016-10-15 21:44:32.355 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 21:44:32.356 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Received Battery Request
2016-10-15 21:44:32.357 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Battery report value = 100
2016-10-15 21:44:32.357 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-15 21:44:32.358 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-15 21:44:32.362 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 100
2016-10-15 21:44:32.364 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:battery-level to 100 [DecimalType]
2016-10-15 21:44:32.371 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class ALARM
2016-10-15 21:44:32.372 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 21:44:32.375 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Received ALARM command V4
2016-10-15 21:44:32.379 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Process NOTIFICATION_REPORT V4
2016-10-15 21:44:32.381 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: NOTIFICATION report - 0 = 0, event=22, status=255
2016-10-15 21:44:32.383 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm Type = ACCESS_CONTROL (0)
2016-10-15 21:44:32.385 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
2016-10-15 21:44:32.386 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2016-10-15 21:44:32.388 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
2016-10-15 21:44:32.392 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_door to OPEN [OpenClosedType]
2016-10-15 21:44:32.397 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-15 21:44:32.399 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 21:44:32.401 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-15 21:44:32.404 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-15 21:44:32.406 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-15 21:44:32.408 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 8
2016-10-15 21:44:32.410 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-15 21:44:32.412 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-15 21:44:32.413 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 8
2016-10-15 21:44:32.414 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_luminance to 8 [DecimalType]
2016-10-15 21:44:32.419 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-15 21:44:32.422 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 21:44:32.425 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-15 21:44:32.427 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-15 21:44:32.430 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-15 21:44:32.432 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 27.5
2016-10-15 21:44:32.433 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-15 21:44:32.434 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-15 21:44:32.436 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 27.5
2016-10-15 21:44:32.437 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 9: Sensor is reporting scale 0, requiring conversion to 0. Value is now 27.5.
2016-10-15 21:44:32.438 [DEBUG] [converter.ZWaveCommandClassConverter] - Converted temperature from 27.5C to 27.5C
2016-10-15 21:44:32.440 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_temperature to 27.5 [DecimalType]
2016-10-15 21:44:32.448 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 10: Transaction not completed: node address inconsistent.  lastSent=10, incoming=255
2016-10-15 21:44:33.910 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 04 00 05 12 60 0D 01 06 32 02 21 34 00 00 02 47 00 09 00 00 01 FC 0A 
2016-10-15 21:44:33.917 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-15 21:44:33.934 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 18 00 04 00 05 12 60 0D 01 06 32 02 21 34 00 00 02 47 00 09 00 00 01 FC 0A 
2016-10-15 21:44:33.943 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 18 00 04 00 05 12 60 0D 01 06 32 02 21 34 00 00 02 47 00 09 00 00 01 FC 0A 
2016-10-15 21:44:33.949 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 05 12 60 0D 01 06 32 02 21 34 00 00 02 47 00 09 00 00 01 FC 
2016-10-15 21:44:33.950 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 5: Application Command Request (ALIVE:DONE)
2016-10-15 21:44:33.952 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
2016-10-15 21:44:33.953 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@12d5472 already registered
2016-10-15 21:44:33.956 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 5: Incoming command class MULTI_INSTANCE
2016-10-15 21:44:33.959 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 5: Received MULTI_INSTANCE command V2
2016-10-15 21:44:33.961 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 5: Requested Command Class = METER (0x32)
2016-10-15 21:44:33.964 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 5: Endpoint = 1, calling handleApplicationCommandRequest.
2016-10-15 21:44:33.966 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5: Received METER command V2
2016-10-15 21:44:33.968 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 5: Meter: Type=Electric(1), Scale=W(2), Value=58.3
2016-10-15 21:44:33.970 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMeterValueEvent
2016-10-15 21:44:33.972 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMeterValueEvent
2016-10-15 21:44:33.973 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 1, command class = METER, value = 58.3
2016-10-15 21:44:33.975 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:ef20d771:node5:meter_watts1 to 58.3 [DecimalType]
2016-10-15 21:44:33.983 [DEBUG] [ternal.converter.ZWaveMeterConverter] - Not the right scale E_KWh <> E_W
2016-10-15 21:44:33.984 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 10: Transaction not completed: node address inconsistent.  lastSent=10, incoming=255
2016-10-15 21:44:34.371 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 24 00 04 00 09 1E 8F 01 04 03 80 03 64 09 71 05 00 00 00 FF 06 17 00 05 31 05 03 01 08 06 31 05 01 22 01 13 0D 
2016-10-15 21:44:34.382 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-15 21:44:34.389 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 24 00 04 00 09 1E 8F 01 04 03 80 03 64 09 71 05 00 00 00 FF 06 17 00 05 31 05 03 01 08 06 31 05 01 22 01 13 0D 
2016-10-15 21:44:34.395 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 24 00 04 00 09 1E 8F 01 04 03 80 03 64 09 71 05 00 00 00 FF 06 17 00 05 31 05 03 01 08 06 31 05 01 22 01 13 0D 
2016-10-15 21:44:34.401 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 1E 8F 01 04 03 80 03 64 09 71 05 00 00 00 FF 06 17 00 05 31 05 03 01 08 06 31 05 01 22 01 13 
2016-10-15 21:44:34.403 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-15 21:44:34.406 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-15 21:44:34.408 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@24ab2d already registered
2016-10-15 21:44:34.410 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-15 21:44:34.411 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-15 21:44:34.413 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class BATTERY
2016-10-15 21:44:34.414 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 21:44:34.416 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Received Battery Request
2016-10-15 21:44:34.417 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Battery report value = 100
2016-10-15 21:44:34.418 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-15 21:44:34.420 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-15 21:44:34.421 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 100
2016-10-15 21:44:34.423 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:battery-level to 100 [DecimalType]
2016-10-15 21:44:34.431 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class ALARM
2016-10-15 21:44:34.436 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 21:44:34.439 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Received ALARM command V4
2016-10-15 21:44:34.440 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Process NOTIFICATION_REPORT V4
2016-10-15 21:44:34.442 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: NOTIFICATION report - 0 = 0, event=23, status=255
2016-10-15 21:44:34.443 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm Type = ACCESS_CONTROL (0)
2016-10-15 21:44:34.444 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
2016-10-15 21:44:34.446 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2016-10-15 21:44:34.447 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
2016-10-15 21:44:34.449 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_door to OPEN [OpenClosedType]
2016-10-15 21:44:34.455 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-15 21:44:34.457 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 21:44:34.458 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-15 21:44:34.460 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-15 21:44:34.462 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-15 21:44:34.463 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 8
2016-10-15 21:44:34.465 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-15 21:44:34.467 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-15 21:44:34.468 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 8
2016-10-15 21:44:34.470 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_luminance to 8 [DecimalType]
2016-10-15 21:44:34.475 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-15 21:44:34.478 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 21:44:34.482 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-15 21:44:34.488 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-15 21:44:34.490 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-15 21:44:34.491 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 27.5
2016-10-15 21:44:34.493 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-15 21:44:34.495 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-15 21:44:34.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 27.5
2016-10-15 21:44:34.509 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 9: Sensor is reporting scale 0, requiring conversion to 0. Value is now 27.5.
2016-10-15 21:44:34.510 [DEBUG] [converter.ZWaveCommandClassConverter] - Converted temperature from 27.5C to 27.5C
2016-10-15 21:44:34.512 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_temperature to 27.5 [DecimalType]

Well, I can’t see anything wrong at the moment. Maybe the build didn’t update - I know some users aren’t getting the latest builds when they are built during the day for some reason.

I’ve just created another PR which just adds a few lines of debugging to try and work out why this isn’t setting to CLOSED. Can you grab this once it’s built and send me the updated open/close log.

Thanks.

stumbeled over this thread

I have many devices that are based on this (philio, dlink, devolo)

never a problem … but you need to set the correct config parameters to make it report door open / close

@schmidmuc did you set your parameter 5 and 7 ?

@shorty707 I use the same parameters I used to in OH1, but on OH2 this causes the described behaviour. Or can you see any failure in the configuration below:

5: Operation Mode = 8

Bit 0: Reserved.
Bit 1: 1 means enable test mode; 0 means normal mode. Notice: Ignored if DIP Switch is not set to Customer Mode. Otherwise it decides by DIP switch setting to Test or Normal mode
Bit 2: Disable the door/window function. (1: Disable, 0: Enable)
Bit 3: Setting the temperature scale (0: Fahrenheit, 1: Celcius)
Bit 4: Disable the illumination report after event triggered (1: Disable, 0: Enable)
Bit 5: Disable the temperature report after event triggered (1: Disable, 0: Enable)
Bit 6: Reserved
Bit 7: Disable the back key release into test mode (1: Disable, 0: Enable) 

7: Customer Function = 4

Bit 0: Reserve.
Bit 1: Enable sending motion OFF report. (0:Disable, 1:Enable) 
Note: Depends on the Bit4, 
Report Notification CC, Type: 0x07, Event: 0xFE 1: Sensor Binary Report, Type: 0x0C, Value: 0x00
Bit 2: Enable PIR super sensitivity mode. (0:Disable, 1:Enable)
Bit 3: Disable send out BASIC OFF after door closed. (1:Disable, 0:Enable)
Bit 4: Notification Type, 
Using Notification Report. 1: Using Sensor Binary Report.
Bit 5: Disable Multi CC in auto report. (1:Disable, 0:Enable)
Bit 6: Disable to report battery state when the device triggered. (1:Disable, 0:Enable)
Bit 7: Reserve.

I set Customer Function to 20
this changes the Notification Type

its working for me this way fully

The “binary_sensor” already shows the right behaviour now, but @chris is implementing a dedicated “door” variable in the thing and this one is only showing “open”.

Again some logs with latest snapshot - this time many lines were created - and unfortunately the door contact still not working…

2016-10-18 21:11:00.047 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:00.051 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 46 CA 00 76 
2016-10-18 21:11:00.052 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 05 00 46 CA 00 76 
2016-10-18 21:11:00.053 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AssignReturnRoute[0x46], type=Request[0x00], priority=High, dest=255, callback=0, payload=CA 00 
2016-10-18 21:11:00.054 [DEBUG] [essage.AssignReturnRouteMessageClass] - NODE 9: Got AssignReturnRoute request.
2016-10-18 21:11:00.055 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveNetworkEvent
2016-10-18 21:11:00.058 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveNetworkEvent
2016-10-18 21:11:00.061 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AssignReturnRoute[0x46], type=Request[0x00], priority=High, dest=255, callback=0, payload=09 01 CA 
2016-10-18 21:11:00.072 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AssignReturnRoute[0x46], type=Request[0x00], priority=High, dest=255, callback=0, payload=CA 00 
2016-10-18 21:11:00.077 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AssignReturnRoute, callback id=0, expected=AssignReturnRoute, cancelled=false        transaction complete!
2016-10-18 21:11:00.080 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2016-10-18 21:11:00.081 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - RETURN_ROUTES: Transaction complete (AssignReturnRoute:Request) success(true)
2016-10-18 21:11:00.086 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - checking initialisation queue. Queue size 1.
2016-10-18 21:11:00.088 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - message removed from queue. Queue size 0.
2016-10-18 21:11:00.089 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - RETURN_ROUTES: queue length(0), free to send(true)
2016-10-18 21:11:00.090 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - RETURN_ROUTES try 1: stageAdvanced(false)
2016-10-18 21:11:00.093 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - advancing to NEIGHBORS
2016-10-18 21:11:00.094 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInitializationStateEvent
2016-10-18 21:11:00.096 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2016-10-18 21:11:00.099 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - NEIGHBORS try 0: stageAdvanced(true)
2016-10-18 21:11:00.100 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: NEIGHBORS - get RoutingInfo
2016-10-18 21:11:00.101 [DEBUG] [almessage.GetRoutingInfoMessageClass] - NODE 9: Request routing info
2016-10-18 21:11:00.102 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - queued packet. Queue length is 1
2016-10-18 21:11:00.104 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 2. Queue={}
2016-10-18 21:11:00.105 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 3175ms/3681ms.
2016-10-18 21:11:00.107 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 1
2016-10-18 21:11:00.108 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 09 02 84 08 25 CB 8C 
2016-10-18 21:11:00.114 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 09 00 13 09 02 84 08 25 CB 8C 
2016-10-18 21:11:00.138 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2016-10-18 21:11:00.146 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:00.148 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2016-10-18 21:11:00.149 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 CB 00 00 03 23 
2016-10-18 21:11:00.150 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2016-10-18 21:11:00.152 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2016-10-18 21:11:00.153 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: Sent Data successfully placed on stack.
2016-10-18 21:11:00.163 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:00.183 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 CB 00 00 03 00 00 2D 
2016-10-18 21:11:00.197 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 CB 00 00 03 00 00 2D 
2016-10-18 21:11:00.198 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=CB 00 00 03 
2016-10-18 21:11:00.199 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 203, Status = Transmission complete and ACK received(0)
2016-10-18 21:11:00.207 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=9, callback=203, payload=09 02 84 08 
2016-10-18 21:11:00.234 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=CB 00 00 03 
2016-10-18 21:11:00.235 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=203, expected=SendData, cancelled=false        transaction complete!
2016-10-18 21:11:00.238 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2016-10-18 21:11:00.242 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - NEIGHBORS: Transaction complete (SendData:Request) success(true)
2016-10-18 21:11:00.242 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Could not create table for item 'WohnzimmerTemperaturEssecke' with statement 'CREATE TABLE Item1129 (Time DATETIME, Value DOUBLE, PRIMARY KEY(Time));': CREATE command denied to user 'openhab'@'localhost' for table 'Item1129'
2016-10-18 21:11:00.244 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Item 'WohnzimmerTemperaturEssecke' was not added to the table - removing index
2016-10-18 21:11:00.248 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - checking initialisation queue. Queue size 1.
2016-10-18 21:11:00.250 [DEBUG] [curityCommandClassWithInitialization] - NODE 9: updating lastSentMessageTimestamp
2016-10-18 21:11:00.254 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Went to sleep
2016-10-18 21:11:00.258 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Could not store item 'WohnzimmerTemperaturEssecke' in database with statement 'INSERT INTO Item1129 (TIME, VALUE) VALUES(NOW(),?) ON DUPLICATE KEY UPDATE VALUE=?;': Table 'openhab.Item1129' doesn't exist
2016-10-18 21:11:00.261 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Is sleeping
2016-10-18 21:11:00.262 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2016-10-18 21:11:00.280 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 9: Response processed after 165ms/3681ms.
2016-10-18 21:11:00.281 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2016-10-18 21:11:00.282 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 80 09 00 00 03 72 
2016-10-18 21:11:00.296 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 07 00 80 09 00 00 03 72 
2016-10-18 21:11:00.326 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 01 80 B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 EF 
2016-10-18 21:11:00.286 [ERROR] [ntime.internal.engine.ExecuteRuleJob] - Error during the execution of rule Energie: Verbrauch und Kosten berechnen: org.eclipse.smarthome.core.library.types.DecimalType
2016-10-18 21:11:00.334 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Error count exceeded 1. Disconnecting database.
2016-10-18 21:11:00.353 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:00.356 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 20 01 80 B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 EF 
2016-10-18 21:11:00.358 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 20 01 80 B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 EF 
2016-10-18 21:11:00.360 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=GetRoutingInfo[0x80], type=Response[0x01], priority=High, dest=255, callback=0, payload=B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2016-10-18 21:11:00.362 [DEBUG] [almessage.GetRoutingInfoMessageClass] - NODE 9: Got NodeRoutingInfo request.
2016-10-18 21:11:00.363 [DEBUG] [almessage.GetRoutingInfoMessageClass] - NODE 9: Neighbor nodes: 1 5 6 8
2016-10-18 21:11:00.367 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveNetworkEvent
2016-10-18 21:11:00.371 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveNetworkEvent
2016-10-18 21:11:00.379 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=GetRoutingInfo[0x80], type=Request[0x00], priority=High, dest=255, callback=0, payload=09 00 00 03 
2016-10-18 21:11:00.391 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=GetRoutingInfo[0x80], type=Response[0x01], priority=High, dest=255, callback=0, payload=B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2016-10-18 21:11:00.392 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=GetRoutingInfo, callback id=0, expected=GetRoutingInfo, cancelled=false        transaction complete!
2016-10-18 21:11:00.394 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2016-10-18 21:11:00.395 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - NEIGHBORS: Transaction complete (GetRoutingInfo:Request) success(true)
2016-10-18 21:11:00.396 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - checking initialisation queue. Queue size 1.
2016-10-18 21:11:00.398 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - message removed from queue. Queue size 0.
2016-10-18 21:11:00.399 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - NEIGHBORS: queue length(0), free to send(true)
2016-10-18 21:11:00.400 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - NEIGHBORS try 1: stageAdvanced(false)
2016-10-18 21:11:00.402 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - advancing to DONE
2016-10-18 21:11:00.405 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInitializationStateEvent
2016-10-18 21:11:00.412 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveInitializationStateEvent
2016-10-18 21:11:00.426 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating node properties.
2016-10-18 21:11:00.428 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating node properties. MAN=316
2016-10-18 21:11:00.429 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating node properties. MAN=316. SET. Was 316
2016-10-18 21:11:00.431 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.432 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.433 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.434 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.435 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.436 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.437 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.438 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.439 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.440 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.441 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.442 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.443 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.444 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.445 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.446 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.447 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.451 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.452 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.453 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.454 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.456 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.457 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - size = '1'
2016-10-18 21:11:00.459 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_2_1 is FFFFFFFF
2016-10-18 21:11:00.460 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  FFFFFFFF>>FFFFFFFF
2016-10-18 21:11:00.461 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing FFFFFFFF>>FFFFFFFF
2016-10-18 21:11:00.462 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_2_1 is FFFFFFFF [-1]
2016-10-18 21:11:00.463 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Could not create table for item 'WohnzimmerTemperatur' with statement 'CREATE TABLE Item1130 (Time DATETIME, Value DOUBLE, PRIMARY KEY(Time));': CREATE command denied to user 'openhab'@'localhost' for table 'Item1130'
2016-10-18 21:11:00.463 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.464 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.465 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.465 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Item 'WohnzimmerTemperatur' was not added to the table - removing index
2016-10-18 21:11:00.466 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.467 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.468 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.469 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.474 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.475 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.476 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.477 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.478 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.478 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.479 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.480 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.481 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.482 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.483 [ERROR] [sql.internal.MysqlPersistenceService] - mySQL: Could not store item 'WohnzimmerTemperatur' in database with statement 'INSERT INTO Item1130 (TIME, VALUE) VALUES(NOW(),?) ON DUPLICATE KEY UPDATE VALUE=?;': Table 'openhab.Item1130' doesn't exist
2016-10-18 21:11:00.483 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.484 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.487 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.488 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.488 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.489 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.489 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.490 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.490 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.491 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.491 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.492 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.493 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.497 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.498 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.498 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.499 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.500 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.500 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.501 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.502 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.503 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.504 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.504 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.506 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.509 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.510 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.511 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.512 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.513 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.514 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.522 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.523 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.524 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.526 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.527 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.528 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.528 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.529 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.530 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.531 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.532 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.533 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.538 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.539 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.548 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.549 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.550 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.551 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.552 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - size = '1'
2016-10-18 21:11:00.559 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_4_1 is 00000064
2016-10-18 21:11:00.560 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000064>>FFFFFFFF
2016-10-18 21:11:00.562 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000064>>FFFFFFFF
2016-10-18 21:11:00.563 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_4_1 is 00000064 [100]
2016-10-18 21:11:00.564 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.566 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.567 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.568 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.570 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.574 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.592 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.600 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.602 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.604 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.605 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.606 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.608 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.609 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.612 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.613 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.614 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.615 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.616 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.616 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.617 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.618 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.619 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.620 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.621 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.621 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.625 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.626 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.627 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.628 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.629 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.630 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.632 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.633 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.634 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.636 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.636 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.637 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.641 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.642 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.643 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.645 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.647 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.648 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.649 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.650 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.651 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.652 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.654 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.657 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.658 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.659 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.660 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.661 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.662 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.662 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - size = '1'
2016-10-18 21:11:00.663 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_5_1 is 00000008
2016-10-18 21:11:00.665 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000008>>FFFFFFFF
2016-10-18 21:11:00.666 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000008>>FFFFFFFF
2016-10-18 21:11:00.667 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_5_1 is 00000008 [8]
2016-10-18 21:11:00.669 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.670 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.670 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.671 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.672 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.672 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.673 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.674 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.674 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.675 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.676 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.676 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.680 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.681 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.682 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.682 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.683 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.684 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.684 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.685 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.685 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.686 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.686 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.687 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.687 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.688 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.688 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.689 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.689 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.690 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.691 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.691 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.692 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.692 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.693 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.694 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.695 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.695 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.696 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.697 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.697 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.698 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.698 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.699 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.699 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.700 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.701 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.701 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.702 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.703 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.703 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.704 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.705 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - size = '1'
2016-10-18 21:11:00.706 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_6_1 is 00000006
2016-10-18 21:11:00.707 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000006>>FFFFFFFF
2016-10-18 21:11:00.708 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000006>>FFFFFFFF
2016-10-18 21:11:00.708 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_6_1 is 00000006 [6]
2016-10-18 21:11:00.709 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.710 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.710 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.711 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.711 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.712 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.712 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.713 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.713 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.714 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.715 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.715 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.716 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.716 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.717 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.718 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.718 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.719 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.719 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.720 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.720 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.721 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.722 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.722 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.723 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.723 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.724 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.725 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.725 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - size = '1'
2016-10-18 21:11:00.728 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_7_1 is 00000004
2016-10-18 21:11:00.729 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000004>>FFFFFFFF
2016-10-18 21:11:00.732 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000004>>FFFFFFFF
2016-10-18 21:11:00.733 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_7_1 is 00000004 [4]
2016-10-18 21:11:00.733 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.734 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.735 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.735 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.736 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.736 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.737 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.737 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.737 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.738 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.738 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.739 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.739 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.739 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.740 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.740 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.741 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.741 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.742 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.742 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.743 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.744 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.745 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.745 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.746 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.746 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.747 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.747 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.748 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.748 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.749 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.749 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.750 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.750 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.750 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.751 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.754 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.754 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.756 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.757 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.758 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.759 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.759 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.760 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.761 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.761 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.762 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.763 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.763 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.764 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.765 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.766 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.767 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - size = '1'
2016-10-18 21:11:00.767 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_8_1 is 00000003
2016-10-18 21:11:00.768 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000003>>FFFFFFFF
2016-10-18 21:11:00.769 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000003>>FFFFFFFF
2016-10-18 21:11:00.770 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_8_1 is 00000003 [3]
2016-10-18 21:11:00.770 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.771 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.772 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.773 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.774 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.774 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.775 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.776 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.776 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.777 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.777 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.778 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.779 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.780 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.780 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.782 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.782 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.783 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.784 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.784 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.785 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.785 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.786 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.787 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.787 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.788 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.789 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.790 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.790 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.791 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.792 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.793 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.793 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.794 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.795 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.795 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.796 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.796 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.797 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.802 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.803 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.804 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.805 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.806 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.807 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.807 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.808 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.808 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.809 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.810 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - size = '1'
2016-10-18 21:11:00.811 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_9_1 is 00000004
2016-10-18 21:11:00.811 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000004>>FFFFFFFF
2016-10-18 21:11:00.815 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000004>>FFFFFFFF
2016-10-18 21:11:00.816 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_9_1 is 00000004 [4]
2016-10-18 21:11:00.816 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.817 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.818 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.819 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.820 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.821 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.821 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.822 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.823 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.823 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.824 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.824 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.825 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.825 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.826 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.827 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.827 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.833 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.834 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.835 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.836 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.836 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.841 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.842 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.843 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.844 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.845 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.846 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.846 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - size = '1'
2016-10-18 21:11:00.848 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_10_1 is 0000000C
2016-10-18 21:11:00.848 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  0000000C>>FFFFFFFF
2016-10-18 21:11:00.849 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 0000000C>>FFFFFFFF
2016-10-18 21:11:00.850 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_10_1 is 0000000C [12]
2016-10-18 21:11:00.851 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.851 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.852 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.853 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.854 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.855 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.856 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.856 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.857 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.858 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.858 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.859 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.860 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.860 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.861 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.862 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.863 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.863 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.864 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.865 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.865 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.866 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.867 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.868 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.869 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.870 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.871 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.871 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.872 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.873 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.874 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.875 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.875 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.877 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.879 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.880 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.881 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.882 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.883 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.884 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.885 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.886 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.887 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.888 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.888 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.889 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.890 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - size = '1'
2016-10-18 21:11:00.891 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_11_1 is 0000000C
2016-10-18 21:11:00.895 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  0000000C>>FFFFFFFF
2016-10-18 21:11:00.896 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 0000000C>>FFFFFFFF
2016-10-18 21:11:00.897 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_11_1 is 0000000C [12]
2016-10-18 21:11:00.899 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.899 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.900 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.901 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.902 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.903 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.904 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.905 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.905 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.906 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.907 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.911 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.912 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.912 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.913 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.914 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.915 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.916 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.917 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.918 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.918 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.919 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.920 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.921 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.921 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.922 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.923 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.924 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.925 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.926 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.926 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.927 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.928 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.929 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.930 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.931 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.931 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.932 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.933 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.934 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.934 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.935 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.938 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.941 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.941 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.942 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.943 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.944 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.944 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.945 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.946 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.946 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.947 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.947 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.948 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.949 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.949 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.950 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.950 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.951 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.952 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.952 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.953 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.953 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.954 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.955 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.955 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.956 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.958 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.959 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.959 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - size = '1'
2016-10-18 21:11:00.960 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_12_1 is 0000001E
2016-10-18 21:11:00.961 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  0000001E>>FFFFFFFF
2016-10-18 21:11:00.962 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 0000001E>>FFFFFFFF
2016-10-18 21:11:00.963 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_12_1 is 0000001E [30]
2016-10-18 21:11:00.963 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.964 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.964 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.965 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.965 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.966 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.968 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.969 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.970 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.970 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.971 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.971 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.972 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.972 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:00.973 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:00.974 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:00.974 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:00.975 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:00.975 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:00.975 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:00.976 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:00.976 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:00.977 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:00.977 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:00.978 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:00.978 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:00.979 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:00.979 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:00.979 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:00.980 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:00.980 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:00.981 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:00.981 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:00.982 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:00.982 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:00.983 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:00.983 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:00.984 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:00.984 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:00.985 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:00.985 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:00.986 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:00.986 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:00.987 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:00.987 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:00.988 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:00.988 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:00.989 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:00.989 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:00.990 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:00.990 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:00.991 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:00.991 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:00.992 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:00.992 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:00.992 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - size = '1'
2016-10-18 21:11:00.993 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_13_1 is 00000006
2016-10-18 21:11:00.994 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000006>>FFFFFFFF
2016-10-18 21:11:00.995 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000006>>FFFFFFFF
2016-10-18 21:11:00.995 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_13_1 is 00000006 [6]
2016-10-18 21:11:00.996 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:00.996 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:00.997 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:00.997 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:00.998 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:00.998 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:00.999 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:00.999 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:01.000 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:01.000 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:01.000 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - size = '1'
2016-10-18 21:11:01.001 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_20_1 is 0000001E
2016-10-18 21:11:01.002 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  0000001E>>FFFFFFFF
2016-10-18 21:11:01.003 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 0000001E>>FFFFFFFF
2016-10-18 21:11:01.004 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_20_1 is 0000001E [30]
2016-10-18 21:11:01.004 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:01.005 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:01.005 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:01.006 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:01.006 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:01.007 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:01.007 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:01.008 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:01.008 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:01.009 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:01.009 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:01.010 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:01.014 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:01.014 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:01.015 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:01.016 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:01.017 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:01.018 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:01.018 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:01.019 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:01.020 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:01.021 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:01.021 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:01.022 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:01.023 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:01.024 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:01.025 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:01.025 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:01.026 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:01.027 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:01.028 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:01.029 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:01.032 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:01.033 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:01.034 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:01.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:01.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:01.038 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:01.039 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:01.040 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:01.041 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:01.042 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:01.042 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:01.043 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:01.047 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:01.048 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:01.052 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:01.053 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:01.053 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:01.054 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:01.055 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:01.056 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:01.056 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - size = '1'
2016-10-18 21:11:01.057 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_21_1 is 00000001
2016-10-18 21:11:01.058 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000001>>FFFFFFFF
2016-10-18 21:11:01.059 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000001>>FFFFFFFF
2016-10-18 21:11:01.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_21_1 is 00000001 [1]
2016-10-18 21:11:01.061 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:01.062 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:01.062 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:01.063 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:01.064 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:01.064 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:01.065 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:01.065 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:01.066 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:01.066 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:01.067 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:01.068 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:01.068 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:01.069 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:01.069 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:01.070 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:01.071 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:01.072 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:01.072 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:01.072 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:01.074 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:01.074 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:01.075 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:01.076 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:01.078 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:01.080 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:01.082 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:01.083 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:01.084 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:01.086 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:01.087 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:01.088 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:01.089 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:01.090 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:01.091 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:01.091 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:01.092 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:01.094 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:01.097 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:01.098 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:01.098 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:01.099 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:01.100 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-18 21:11:01.101 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-18 21:11:01.102 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-18 21:11:01.102 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-18 21:11:01.103 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-18 21:11:01.104 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-18 21:11:01.105 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-18 21:11:01.105 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-18 21:11:01.106 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-18 21:11:01.107 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-18 21:11:01.109 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - size = '1'
2016-10-18 21:11:01.110 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_22_1 is 00000001
2016-10-18 21:11:01.112 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000001>>FFFFFFFF
2016-10-18 21:11:01.113 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000001>>FFFFFFFF
2016-10-18 21:11:01.114 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_22_1 is 00000001 [1]
2016-10-18 21:11:01.115 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-18 21:11:01.115 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-18 21:11:01.116 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-18 21:11:01.117 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-18 21:11:01.118 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-18 21:11:01.119 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-18 21:11:01.120 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-18 21:11:01.120 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc
2016-10-18 21:11:01.121 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-18 21:11:01.122 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-18 21:11:01.123 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-18 21:11:01.124 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-18 21:11:01.125 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-18 21:11:01.125 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd
2016-10-18 21:11:01.126 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-18 21:11:01.127 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-18 21:11:01.128 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-18 21:11:01.128 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-18 21:11:01.129 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-18 21:11:01.132 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-18 21:11:01.134 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-18 21:11:01.134 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-18 21:11:01.135 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-18 21:11:01.136 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-18 21:11:01.137 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-18 21:11:01.137 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-18 21:11:01.141 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-18 21:11:01.141 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-18 21:11:01.142 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-18 21:11:01.142 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-18 21:11:01.143 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-18 21:11:01.144 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-18 21:11:01.144 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-18 21:11:01.146 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-18 21:11:01.147 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-18 21:11:01.148 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-18 21:11:01.149 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-18 21:11:01.149 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-18 21:11:01.150 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-18 21:11:01.153 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Update ASSOCIATION group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@1d61fdd: Adding node_1_0
2016-10-18 21:11:01.154 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Update ASSOCIATION group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@c5cfdc: Adding node_1_0
2016-10-18 21:11:01.156 [ERROR] [ve.internal.protocol.ZWaveController] - Exception during ZWave thread: Input 2. {}
java.lang.NullPointerException
	at org.openhab.binding.zwave.handler.ZWaveThingHandler.updateNodeProperties(ZWaveThingHandler.java:1392)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.handler.ZWaveThingHandler.ZWaveIncomingEvent(ZWaveThingHandler.java:1169)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.notifyEventListeners(ZWaveController.java:546)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.advanceNodeStage(ZWaveNodeInitStageAdvancer.java:1140)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.handleNodeQueue(ZWaveNodeInitStageAdvancer.java:230)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer.ZWaveIncomingEvent(ZWaveNodeInitStageAdvancer.java:1294)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.notifyEventListeners(ZWaveController.java:546)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.handleIncomingResponseMessage(ZWaveController.java:292)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.handleIncomingMessage(ZWaveController.java:216)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.access$7(ZWaveController.java:207)[194:org.openhab.binding.zwave:2.0.0.201610091257]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController$ZWaveInputThread.run(ZWaveController.java:1303)[194:org.openhab.binding.zwave:2.0.0.201610091257]
2016-10-18 21:11:04.563 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 13 00 04 00 09 0D 8F 01 01 09 71 05 00 00 00 FF 07 03 00 E5 
2016-10-18 21:11:04.572 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:04.574 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 13 00 04 00 09 0D 8F 01 01 09 71 05 00 00 00 FF 07 03 00 E5 
2016-10-18 21:11:04.576 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 13 00 04 00 09 0D 8F 01 01 09 71 05 00 00 00 FF 07 03 00 E5 
2016-10-18 21:11:04.587 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 0D 8F 01 01 09 71 05 00 00 00 FF 07 03 00 
2016-10-18 21:11:04.589 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-18 21:11:04.590 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-18 21:11:04.591 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1017754 already registered
2016-10-18 21:11:04.592 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-18 21:11:04.601 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-18 21:11:04.602 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class ALARM
2016-10-18 21:11:04.604 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-18 21:11:04.605 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Received ALARM command V4
2016-10-18 21:11:04.606 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Process NOTIFICATION_REPORT V4
2016-10-18 21:11:04.607 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: NOTIFICATION report - 0 = 0, event=3, status=255
2016-10-18 21:11:04.608 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm Type = BURGLAR (0)
2016-10-18 21:11:04.609 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
2016-10-18 21:11:04.610 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2016-10-18 21:11:04.611 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
2016-10-18 21:11:04.613 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 255: Transaction not completed: node address inconsistent.  lastSent=255, incoming=255
2016-10-18 21:11:05.305 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 2 attempts left!
2016-10-18 21:11:05.306 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2016-10-18 21:11:05.308 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2016-10-18 21:11:05.310 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 80 09 00 00 03 72 
2016-10-18 21:11:05.311 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 07 00 80 09 00 00 03 72 
2016-10-18 21:11:05.331 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 01 80 B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 EF 
2016-10-18 21:11:05.377 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:05.380 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 20 01 80 B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 EF 
2016-10-18 21:11:05.383 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 20 01 80 B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 EF 
2016-10-18 21:11:05.388 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=GetRoutingInfo[0x80], type=Response[0x01], priority=High, dest=255, callback=0, payload=B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2016-10-18 21:11:05.389 [DEBUG] [almessage.GetRoutingInfoMessageClass] - NODE 9: Got NodeRoutingInfo request.
2016-10-18 21:11:05.390 [DEBUG] [almessage.GetRoutingInfoMessageClass] - NODE 9: Neighbor nodes: 1 5 6 8
2016-10-18 21:11:05.391 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveNetworkEvent
2016-10-18 21:11:05.392 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveNetworkEvent
2016-10-18 21:11:05.394 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=GetRoutingInfo[0x80], type=Request[0x00], priority=High, dest=255, callback=0, payload=09 00 00 03 
2016-10-18 21:11:05.397 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=GetRoutingInfo[0x80], type=Response[0x01], priority=High, dest=255, callback=0, payload=B1 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 
2016-10-18 21:11:05.397 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=GetRoutingInfo, callback id=0, expected=GetRoutingInfo, cancelled=false        transaction complete!
2016-10-18 21:11:05.398 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2016-10-18 21:11:05.399 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 87ms/3681ms.
2016-10-18 21:11:06.722 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 09 02 84 07 7B 
2016-10-18 21:11:06.735 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:06.736 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 04 00 09 02 84 07 7B 
2016-10-18 21:11:06.737 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 08 00 04 00 09 02 84 07 7B 
2016-10-18 21:11:06.739 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 02 84 07 
2016-10-18 21:11:06.740 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-18 21:11:06.741 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-18 21:11:06.741 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1017754 already registered
2016-10-18 21:11:06.742 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class WAKE_UP
2016-10-18 21:11:06.742 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Received Wake Up Request
2016-10-18 21:11:06.743 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Received WAKE_UP_NOTIFICATION
2016-10-18 21:11:06.743 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Is awake with 0 messages in the wake-up queue.
2016-10-18 21:11:06.744 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveWakeUpEvent
2016-10-18 21:11:06.745 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveWakeUpEvent
2016-10-18 21:11:06.748 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 255: Transaction not completed: node address inconsistent.  lastSent=255, incoming=255
2016-10-18 21:11:07.749 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: No more messages, go back to sleep
2016-10-18 21:11:07.749 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2016-10-18 21:11:07.750 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2016-10-18 21:11:07.751 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2016-10-18 21:11:07.752 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 09 02 84 08 25 CC 8B 
2016-10-18 21:11:07.754 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 09 00 13 09 02 84 08 25 CC 8B 
2016-10-18 21:11:07.783 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2016-10-18 21:11:07.789 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:07.790 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2016-10-18 21:11:07.791 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2016-10-18 21:11:07.793 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2016-10-18 21:11:07.794 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: Sent Data successfully placed on stack.
2016-10-18 21:11:07.802 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 CC 00 00 03 24 
2016-10-18 21:11:07.821 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:07.823 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 CC 00 00 03 00 00 2A 
2016-10-18 21:11:07.828 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 CC 00 00 03 00 00 2A 
2016-10-18 21:11:07.829 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=CC 00 00 03 
2016-10-18 21:11:07.831 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 204, Status = Transmission complete and ACK received(0)
2016-10-18 21:11:07.834 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-18 21:11:07.835 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1017754 already registered
2016-10-18 21:11:07.837 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=9, callback=204, payload=09 02 84 08 
2016-10-18 21:11:07.841 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=CC 00 00 03 
2016-10-18 21:11:07.842 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=204, expected=SendData, cancelled=false        transaction complete!
2016-10-18 21:11:07.843 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2016-10-18 21:11:07.864 [DEBUG] [curityCommandClassWithInitialization] - NODE 9: updating lastSentMessageTimestamp
2016-10-18 21:11:07.864 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Went to sleep
2016-10-18 21:11:07.865 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Is sleeping
2016-10-18 21:11:07.865 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2016-10-18 21:11:07.866 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 9: Response processed after 111ms/3681ms.
2016-10-18 21:11:08.281 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 16 00 04 00 09 10 8F 01 02 05 31 05 03 01 09 06 31 05 01 22 01 0E 5C 
2016-10-18 21:11:08.288 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-18 21:11:08.290 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 16 00 04 00 09 10 8F 01 02 05 31 05 03 01 09 06 31 05 01 22 01 0E 5C 
2016-10-18 21:11:08.292 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 16 00 04 00 09 10 8F 01 02 05 31 05 03 01 09 06 31 05 01 22 01 0E 5C 
2016-10-18 21:11:08.294 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 10 8F 01 02 05 31 05 03 01 09 06 31 05 01 22 01 0E 
2016-10-18 21:11:08.295 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-18 21:11:08.295 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-18 21:11:08.296 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1017754 already registered
2016-10-18 21:11:08.297 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-18 21:11:08.297 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-18 21:11:08.298 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-18 21:11:08.298 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-18 21:11:08.299 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-18 21:11:08.300 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-18 21:11:08.300 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-18 21:11:08.301 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 9
2016-10-18 21:11:08.301 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-18 21:11:08.302 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-18 21:11:08.303 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 9
2016-10-18 21:11:08.304 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_luminance to 9 [DecimalType]
2016-10-18 21:11:08.309 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-18 21:11:08.311 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-18 21:11:08.312 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-18 21:11:08.313 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-18 21:11:08.313 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-18 21:11:08.314 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 27
2016-10-18 21:11:08.315 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-18 21:11:08.316 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-18 21:11:08.317 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 27
2016-10-18 21:11:08.318 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 9: Sensor is reporting scale 0, requiring conversion to 0. Value is now 27.
2016-10-18 21:11:08.319 [DEBUG] [converter.ZWaveCommandClassConverter] - Converted temperature from 27C to 27C
2016-10-18 21:11:08.320 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_temperature to 27 [DecimalType]

Is it possible to email me the log, or something, so that it’s easier to process? You can email me at chris -at- cd-jackson.com