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

schmidmuc · October 8, 2016, 10:27am

I face problems with the Philio PST02C device - the door contact doesn’t work. I compared the configuration with the one from OpenHAB1 and it could be that the binary sensor has to be set to “basic” mode (in OpenHAB1 it worked with basic mode).

Does anyone see the same problems with this device on OH2?

It’s about the following device:
http://www.cd-jackson.com/index.php/zwave/zwave-device-database/zwave-device-list/devicesummary/170

chris · October 8, 2016, 10:48am

Hi Thomas,
Can you provide a debug log showing what is received when the contact is opened/closed?

Chris

schmidmuc · October 8, 2016, 12:37pm

2016-10-08 14:14:54.966 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:14:54.967 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:14:54.970 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:14:54.971 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:14:54.973 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:14:54.974 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:14:54.975 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:14:54.977 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:14:54.978 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-08 14:14:54.979 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 25
2016-10-08 14:14:54.981 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:14:54.982 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 25
2016-10-08 14:14:54.983 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:17:53.908 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:17:53.918 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:17:53.922 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:17:53.925 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:17:53.927 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:17:53.929 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:17:53.930 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:17:53.932 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:17:53.934 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-08 14:17:53.936 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 7
2016-10-08 14:17:53.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:17:53.941 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 7
2016-10-08 14:17:53.943 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:18:43.465 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:18:43.467 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:18:43.469 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:18:43.470 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:18:43.471 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class BATTERY
2016-10-08 14:18:43.472 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:18:43.474 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Received Battery Request
2016-10-08 14:18:43.475 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Battery report value = 100
2016-10-08 14:18:43.476 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-08 14:18:43.477 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 100
2016-10-08 14:18:43.479 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:d385b628:node9:battery-level to 100 [DecimalType]
2016-10-08 14:18:43.487 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class ALARM
2016-10-08 14:18:43.490 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:18:43.490 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Received Alarm Request V3
2016-10-08 14:18:43.492 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Process Alarm Report, V3, length 33
2016-10-08 14:18:43.494 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm report - 6 = 0, sensor=0, event=23, status=255
2016-10-08 14:18:43.496 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm Type = Access Control (6)
2016-10-08 14:18:43.502 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2016-10-08 14:18:43.503 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
2016-10-08 14:18:43.505 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:18:43.507 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:18:43.508 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:18:43.510 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:18:43.511 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-08 14:18:43.513 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 6
2016-10-08 14:18:43.515 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:18:43.516 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 6
2016-10-08 14:18:43.517 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:18:43.518 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:18:43.519 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:18:43.520 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:18:43.521 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:18:43.522 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-08 14:18:43.522 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 25
2016-10-08 14:18:43.525 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:18:43.526 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 25
2016-10-08 14:18:43.527 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:18:48.510 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:18:48.510 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:18:48.512 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2016-10-08 14:18:48.513 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2016-10-08 14:18:48.513 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2016-10-08 14:18:48.514 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0x00
2016-10-08 14:18:48.516 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-08 14:18:48.516 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2016-10-08 14:18:53.542 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:18:53.543 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:18:53.546 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:18:53.547 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:18:53.548 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:18:53.549 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:18:53.550 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:18:53.551 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:18:53.553 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-08 14:18:53.554 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 6
2016-10-08 14:18:53.557 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:18:53.559 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 6
2016-10-08 14:18:53.561 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:19:53.254 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:19:53.255 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:19:53.258 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:19:53.260 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:19:53.268 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:19:53.269 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:19:53.277 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:19:53.281 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:19:53.282 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-08 14:19:53.283 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 7
2016-10-08 14:19:53.286 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:19:53.289 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 7
2016-10-08 14:19:53.292 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:20:44.743 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:20:44.744 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:20:44.749 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2016-10-08 14:20:44.754 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2016-10-08 14:20:44.761 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2016-10-08 14:20:44.762 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0xFF
2016-10-08 14:20:44.789 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-08 14:20:44.794 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
2016-10-08 14:20:44.811 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:20:44.812 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:20:44.814 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:20:44.817 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:20:44.818 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class BATTERY
2016-10-08 14:20:44.819 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:20:44.820 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Received Battery Request
2016-10-08 14:20:44.823 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Battery report value = 100
2016-10-08 14:20:44.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-08 14:20:44.831 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 100
2016-10-08 14:20:44.834 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:d385b628:node9:battery-level to 100 [DecimalType]
2016-10-08 14:20:44.840 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class ALARM
2016-10-08 14:20:44.841 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:20:44.844 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Received Alarm Request V3
2016-10-08 14:20:44.845 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Process Alarm Report, V3, length 33
2016-10-08 14:20:44.847 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm report - 6 = 0, sensor=0, event=22, status=255
2016-10-08 14:20:44.858 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm Type = Access Control (6)
2016-10-08 14:20:44.860 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2016-10-08 14:20:44.861 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
2016-10-08 14:20:44.863 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:20:44.864 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:20:44.865 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:20:44.866 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:20:44.868 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-08 14:20:44.869 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 7
2016-10-08 14:20:44.872 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:20:44.873 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 7
2016-10-08 14:20:44.875 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:20:44.876 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:20:44.877 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:20:44.878 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:20:44.879 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:20:44.881 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-08 14:20:44.882 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 25
2016-10-08 14:20:44.884 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:20:44.885 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 25
2016-10-08 14:20:44.887 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:20:45.063 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 9: Application update request. Node information received.
2016-10-08 14:20:45.065 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:20:45.067 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Is awake with 0 messages in the wake-up queue.
2016-10-08 14:20:45.070 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveWakeUpEvent
2016-10-08 14:20:45.973 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:20:45.975 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:20:45.979 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class WAKE_UP
2016-10-08 14:20:45.981 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Received Wake Up Request
2016-10-08 14:20:45.982 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Received WAKE_UP_NOTIFICATION
2016-10-08 14:20:45.984 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Is awake with 0 messages in the wake-up queue.
2016-10-08 14:20:45.987 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveWakeUpEvent
2016-10-08 14:20:46.993 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: No more messages, go back to sleep
2016-10-08 14:20:47.000 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2016-10-08 14:20:47.014 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 09 00 13 09 02 84 08 25 85 C2 
2016-10-08 14:20:47.053 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: Sent Data successfully placed on stack.
2016-10-08 14:20:47.103 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 133, Status = Transmission complete and ACK received(0)
2016-10-08 14:20:47.105 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:20:47.116 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2016-10-08 14:20:47.120 [DEBUG] [curityCommandClassWithInitialization] - NODE 9: updating lastSentMessageTimestamp
2016-10-08 14:20:47.123 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Went to sleep
2016-10-08 14:20:47.127 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Is sleeping
2016-10-08 14:20:47.128 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 9: Response processed after 111ms/4902ms.
2016-10-08 14:20:49.518 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:49.558 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:49.622 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:49.766 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:49.951 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:50.045 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:50.250 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:50.527 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:50.619 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:50.934 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:51.173 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:51.303 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:51.493 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:51.658 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:53.024 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:20:53.025 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:20:53.053 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:20:53.058 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:20:53.069 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:20:53.070 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:20:53.071 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:20:53.071 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:20:53.076 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-08 14:20:53.079 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 25
2016-10-08 14:20:53.081 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:20:53.082 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 25
2016-10-08 14:20:53.083 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:20:56.802 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:20:57.367 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent.  lastSent=9, incoming=255
2016-10-08 14:21:16.259 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:21:16.267 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:21:16.276 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2016-10-08 14:21:16.279 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2016-10-08 14:21:16.282 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2016-10-08 14:21:16.284 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0x00
2016-10-08 14:21:16.287 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-08 14:21:16.289 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2016-10-08 14:21:52.769 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:21:52.770 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:21:52.772 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:21:52.773 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:21:52.774 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:21:52.774 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:21:52.775 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:21:52.776 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:21:52.777 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-08 14:21:52.778 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 25
2016-10-08 14:21:52.780 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:21:52.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 25
2016-10-08 14:21:52.782 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:22:52.478 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:22:52.479 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:22:52.481 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:22:52.482 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:22:52.483 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:22:52.484 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:22:52.485 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:22:52.485 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:22:52.486 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-08 14:22:52.487 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 25
2016-10-08 14:22:52.489 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:22:52.490 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 25
2016-10-08 14:22:52.491 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature

chris · October 8, 2016, 12:42pm

Please post a log that hasn’t been filtered as you’ve removed the information I need to process the log.

Also, when you post the logs, to format it please highlight and click on the </> button (thanks).

schmidmuc · October 8, 2016, 12:50pm

Ok, so the log gets longer… The device is NODE 9:

2016-10-08 14:49:11.322 [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 0A 06 31 05 01 22 01 22 3E 
2016-10-08 14:49:11.328 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-08 14:49:11.337 [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 0A 06 31 05 01 22 01 22 3E 
2016-10-08 14:49:11.342 [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 0A 06 31 05 01 22 01 22 3E 
2016-10-08 14:49:11.344 [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 0A 06 31 05 01 22 01 22 
2016-10-08 14:49:11.348 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:49:11.351 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:49:11.353 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@20694d already registered
2016-10-08 14:49:11.354 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:49:11.355 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:49:11.357 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class BATTERY
2016-10-08 14:49:11.358 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:49:11.359 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Received Battery Request
2016-10-08 14:49:11.360 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Battery report value = 100
2016-10-08 14:49:11.361 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-08 14:49:11.363 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-08 14:49:11.366 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 100
2016-10-08 14:49:11.368 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:d385b628:node9:battery-level to 100 [DecimalType]
2016-10-08 14:49:11.372 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class ALARM
2016-10-08 14:49:11.373 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:49:11.374 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Received Alarm Request V3
2016-10-08 14:49:11.375 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Process Alarm Report, V3, length 33
2016-10-08 14:49:11.376 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm report - 6 = 0, sensor=0, event=23, status=255
2016-10-08 14:49:11.377 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm Type = Access Control (6)
2016-10-08 14:49:11.378 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
2016-10-08 14:49:11.383 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2016-10-08 14:49:11.386 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
2016-10-08 14:49:11.387 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:49:11.388 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:49:11.389 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:49:11.390 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:49:11.391 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-08 14:49:11.398 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 1E+1
2016-10-08 14:49:11.399 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:49:11.402 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:49:11.404 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 1E+1
2016-10-08 14:49:11.405 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:49:11.407 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:49:11.409 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:49:11.411 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:49:11.412 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:49:11.412 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 08 0C 60 0D 06 06 31 05 01 44 00 00 08 DC 25 
2016-10-08 14:49:11.413 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-08 14:49:11.414 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 29
2016-10-08 14:49:11.416 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:49:11.418 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:49:11.419 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 29
2016-10-08 14:49:11.421 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature

schmidmuc · October 8, 2016, 12:52pm

And a second log for “door opened”:

2016-10-08 14:49:12.228 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@cc38a6 already registered
2016-10-08 14:49:12.230 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=5, callback=139, payload=05 03 71 04 00 
2016-10-08 14:49:12.231 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=8B 00 00 02 
2016-10-08 14:49:12.232 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=139, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2016-10-08 14:49:13.549 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 20 01 FF 26 
2016-10-08 14:49:13.552 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-08 14:49:13.554 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 20 01 FF 26 
2016-10-08 14:49:13.556 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 20 01 FF 26 
2016-10-08 14:49:13.557 [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-08 14:49:13.558 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:49:13.559 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:49:13.561 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@20694d already registered
2016-10-08 14:49:13.562 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2016-10-08 14:49:13.564 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2016-10-08 14:49:13.566 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2016-10-08 14:49:13.569 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0xFF
2016-10-08 14:49:13.572 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-08 14:49:13.574 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-08 14:49:13.578 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
2016-10-08 14:49:13.581 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 5: Transaction not completed: node address inconsistent.  lastSent=5, incoming=255
2016-10-08 14:49:13.584 [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 0A 06 31 05 01 22 01 22 3F 
2016-10-08 14:49:13.590 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-08 14:49:13.593 [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 0A 06 31 05 01 22 01 22 3F 
2016-10-08 14:49:13.595 [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 0A 06 31 05 01 22 01 22 3F 
2016-10-08 14:49:13.597 [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 0A 06 31 05 01 22 01 22 
2016-10-08 14:49:13.599 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-08 14:49:13.599 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-08 14:49:13.600 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@20694d already registered
2016-10-08 14:49:13.601 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-08 14:49:13.602 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-08 14:49:13.603 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class BATTERY
2016-10-08 14:49:13.603 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:49:13.604 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Received Battery Request
2016-10-08 14:49:13.605 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Battery report value = 100
2016-10-08 14:49:13.606 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-08 14:49:13.606 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-08 14:49:13.607 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 100
2016-10-08 14:49:13.608 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:d385b628:node9:battery-level to 100 [DecimalType]
2016-10-08 14:49:13.612 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class ALARM
2016-10-08 14:49:13.613 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:49:13.613 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Received Alarm Request V3
2016-10-08 14:49:13.614 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Process Alarm Report, V3, length 33
2016-10-08 14:49:13.615 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm report - 6 = 0, sensor=0, event=22, status=255
2016-10-08 14:49:13.616 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm Type = Access Control (6)
2016-10-08 14:49:13.617 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
2016-10-08 14:49:13.618 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2016-10-08 14:49:13.619 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 0
2016-10-08 14:49:13.620 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:49:13.621 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:49:13.622 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:49:13.627 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:49:13.633 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-08 14:49:13.635 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 1E+1
2016-10-08 14:49:13.640 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:49:13.643 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:49:13.644 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 1E+1
2016-10-08 14:49:13.647 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:49:13.648 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-08 14:49:13.649 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-08 14:49:13.649 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received SENSOR_MULTILEVEL command V5
2016-10-08 14:49:13.650 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-08 14:49:13.651 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-08 14:49:13.652 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 29
2016-10-08 14:49:13.652 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:49:13.653 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-08 14:49:13.654 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 29
2016-10-08 14:49:13.655 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: No converter set for channel zwave:device:d385b628:node9:sensor_temperature
2016-10-08 14:49:13.656 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 5: Transaction not completed: node address inconsistent.  lastSent=5, incoming=255
2016-10-08 14:49:17.175 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 5: Timeout while sending message. Requeueing - 0 attempts left!
2016-10-08 14:49:17.176 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 5: Got an error while sending data. Resending message.
2016-10-08 14:49:17.178 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 12. Queue={}
2016-10-08 14:49:17.178 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 11
2016-10-08 14:49:17.179 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 71 04 00 25 8C 3C 
2016-10-08 14:49:17.180 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 71 04 00 25 8C 3C 
2016-10-08 14:49:17.192 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2016-10-08 14:49:17.195 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

chris · October 8, 2016, 1:10pm

Thanks. I’ll update the database shortly to better reflect this. You’ll need to delete the thing, hit the discovery button again in HABmin/PaperUI, and add it back from the inbox so that it picks up the new definition.

schmidmuc · October 8, 2016, 1:18pm

Many thanks… Any summary what finally was the problem?

chris · October 8, 2016, 2:40pm

I changed the channel type to sensor_door. Let’s see if it fixes the problem… Currently Cloudbees seems to be having problems so I need to wait for that to sort itself out before I can rebuild the change.

schmidmuc · October 15, 2016, 4:17pm

Unfortunately the door sensor does not work correctly, even after your last change

chris · October 15, 2016, 4:20pm

Can you provide a log please. Also, can you confirm that you deleted the original thing?

schmidmuc · October 15, 2016, 4:31pm

Yes, I removed the thing and scanned again for it. Log of inclusion attached:

2016-10-15 18:27:12.722 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler.
2016-10-15 18:27:12.757 [DEBUG] [org.openhab.binding.zwave           ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandler}={thing.type=zwave:philio_pst02c_00_000, thing.id=zwave:device:ef20d771:node9, service.id=383, service.bundleid=194, service.scope=singleton} - org.openhab.binding.zwave
2016-10-15 18:27:12.760 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Controller initialised.
2016-10-15 18:27:12.761 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Controller status changed to ONLINE.
2016-10-15 18:27:12.762 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Controller is ONLINE. Starting device initialisation.
2016-10-15 18:27:12.762 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Controller status changed to ONLINE.
2016-10-15 18:27:12.763 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Controller is ONLINE. Starting device initialisation.
2016-10-15 18:27:12.765 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating node properties.
2016-10-15 18:27:12.767 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating node properties. MAN=316
2016-10-15 18:27:12.769 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating node properties. MAN=316. SET. Was 316
2016-10-15 18:27:12.771 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-15 18:27:12.777 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-15 18:27:12.778 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-15 18:27:12.779 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-15 18:27:12.780 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-15 18:27:12.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-15 18:27:12.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-15 18:27:12.782 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-15 18:27:12.783 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-15 18:27:12.784 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-15 18:27:12.785 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-15 18:27:12.792 [DEBUG] [org.openhab.binding.zwave           ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.status.ConfigStatusProvider}={service.id=384, service.bundleid=194, service.scope=singleton} - org.openhab.binding.zwave
2016-10-15 18:27:12.798 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:ef20d771:node9:sensor_binary --> REFRESH
2016-10-15 18:27:12.799 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-15 18:27:12.800 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling intialised at 1800 seconds - start in 50 milliseconds.
2016-10-15 18:27:12.802 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:ef20d771:node9:sensor_temperature --> REFRESH
2016-10-15 18:27:12.803 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling intialised at 1800 seconds - start in 50 milliseconds.
2016-10-15 18:27:12.806 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:ef20d771:node9:sensor_luminance --> REFRESH
2016-10-15 18:27:12.808 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-15 18:27:12.824 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling intialised at 1800 seconds - start in 50 milliseconds.
2016-10-15 18:27:12.824 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-15 18:27:12.825 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-15 18:27:12.826 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-15 18:27:12.827 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:ef20d771:node9:sensor_door --> REFRESH
2016-10-15 18:27:12.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
2016-10-15 18:27:12.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-15 18:27:12.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling intialised at 1800 seconds - start in 50 milliseconds.
2016-10-15 18:27:12.830 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-15 18:27:12.831 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-15 18:27:12.832 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:ef20d771:node9:battery-level --> REFRESH
2016-10-15 18:27:12.832 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
2016-10-15 18:27:12.848 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling intialised at 1800 seconds - start in 50 milliseconds.
2016-10-15 18:27:12.848 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-15 18:27:12.850 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-15 18:27:12.851 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-15 18:27:12.852 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - size = '1'
2016-10-15 18:27:12.853 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_2_1 is FFFFFFFF
2016-10-15 18:27:12.855 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  FFFFFFFF>>FFFFFFFF
2016-10-15 18:27:12.866 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing FFFFFFFF>>FFFFFFFF
2016-10-15 18:27:12.870 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_2_1 is FFFFFFFF [-1]
2016-10-15 18:27:12.874 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-15 18:27:12.877 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-15 18:27:12.880 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-15 18:27:12.888 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-15 18:27:12.890 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-15 18:27:12.897 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-15 18:27:12.898 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling...
2016-10-15 18:27:12.899 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-15 18:27:12.902 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-15 18:27:12.904 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-15 18:27:12.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-15 18:27:12.917 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-15 18:27:12.920 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-15 18:27:12.922 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-15 18:27:12.923 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-15 18:27:12.927 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-15 18:27:12.937 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-15 18:27:12.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-15 18:27:12.941 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-15 18:27:12.942 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-15 18:27:12.944 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-15 18:27:12.945 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-15 18:27:12.947 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-15 18:27:12.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-15 18:27:12.959 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-15 18:27:12.961 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-15 18:27:12.963 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-15 18:27:12.964 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-15 18:27:12.966 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-15 18:27:12.967 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-15 18:27:12.969 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-15 18:27:12.971 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-15 18:27:12.974 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-15 18:27:12.976 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-15 18:27:12.978 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-15 18:27:12.980 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-15 18:27:12.982 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-15 18:27:12.983 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-15 18:27:12.985 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-15 18:27:12.986 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-15 18:27:12.988 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-15 18:27:12.990 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-15 18:27:12.992 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
2016-10-15 18:27:12.994 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-15 18:27:12.996 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-15 18:27:13.008 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-15 18:27:13.010 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
2016-10-15 18:27:13.012 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-15 18:27:13.014 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-15 18:27:13.016 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-15 18:27:13.018 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-15 18:27:13.019 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-15 18:27:13.023 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-15 18:27:13.024 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-15 18:27:13.026 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-15 18:27:13.028 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-15 18:27:13.030 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-15 18:27:13.032 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-15 18:27:13.034 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-15 18:27:13.035 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-15 18:27:13.036 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-15 18:27:13.037 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-15 18:27:13.038 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-15 18:27:13.040 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-15 18:27:13.041 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-15 18:27:13.043 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-15 18:27:13.045 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - size = '1'
2016-10-15 18:27:13.061 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_4_1 is 00000064
2016-10-15 18:27:13.067 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000064>>FFFFFFFF
2016-10-15 18:27:13.081 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000064>>FFFFFFFF
2016-10-15 18:27:13.089 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_4_1 is 00000064 [100]
2016-10-15 18:27:13.096 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-15 18:27:13.100 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-15 18:27:13.103 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-15 18:27:13.106 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-15 18:27:13.109 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-15 18:27:13.111 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-15 18:27:13.113 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-15 18:27:13.115 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-15 18:27:13.116 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-15 18:27:13.118 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-15 18:27:13.120 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-15 18:27:13.122 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-15 18:27:13.124 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-15 18:27:13.125 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-15 18:27:13.127 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-15 18:27:13.129 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-15 18:27:13.131 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-15 18:27:13.133 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-15 18:27:13.135 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-15 18:27:13.137 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-15 18:27:13.139 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-15 18:27:13.141 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-15 18:27:13.143 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-15 18:27:13.145 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-15 18:27:13.147 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-15 18:27:13.149 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
2016-10-15 18:27:13.151 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-15 18:27:13.153 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-15 18:27:13.155 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-15 18:27:13.157 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
2016-10-15 18:27:13.160 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-15 18:27:13.162 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-15 18:27:13.164 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-15 18:27:13.166 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-15 18:27:13.168 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-15 18:27:13.169 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-15 18:27:13.171 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-15 18:27:13.173 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-15 18:27:13.175 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-15 18:27:13.177 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-15 18:27:13.179 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-15 18:27:13.180 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-15 18:27:13.182 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-15 18:27:13.184 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-15 18:27:13.185 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-15 18:27:13.187 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-15 18:27:13.190 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-15 18:27:13.191 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-15 18:27:13.193 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-15 18:27:13.195 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-15 18:27:13.197 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-15 18:27:13.199 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-15 18:27:13.201 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-15 18:27:13.203 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-15 18:27:13.205 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-15 18:27:13.208 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - size = '1'
2016-10-15 18:27:13.210 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_5_1 is 00000008
2016-10-15 18:27:13.212 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000008>>FFFFFFFF
2016-10-15 18:27:13.214 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000008>>FFFFFFFF
2016-10-15 18:27:13.215 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update received
2016-10-15 18:27:13.216 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_5_1 is 00000008 [8]
2016-10-15 18:27:13.218 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-15 18:27:13.220 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-15 18:27:13.222 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-15 18:27:13.224 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-15 18:27:13.226 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-15 18:27:13.229 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-15 18:27:13.231 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-15 18:27:13.233 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-15 18:27:13.235 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-15 18:27:13.237 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-15 18:27:13.239 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-15 18:27:13.241 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-15 18:27:13.243 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-15 18:27:13.245 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-15 18:27:13.247 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-15 18:27:13.249 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-15 18:27:13.251 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-15 18:27:13.253 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-15 18:27:13.254 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-15 18:27:13.256 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
2016-10-15 18:27:13.258 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-15 18:27:13.260 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-15 18:27:13.262 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-15 18:27:13.264 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
2016-10-15 18:27:13.266 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-15 18:27:13.268 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-15 18:27:13.270 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-15 18:27:13.272 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-15 18:27:13.273 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-15 18:27:13.275 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-15 18:27:13.278 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-15 18:27:13.279 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-15 18:27:13.281 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-15 18:27:13.283 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-15 18:27:13.285 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-15 18:27:13.287 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-15 18:27:13.289 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-15 18:27:13.291 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-15 18:27:13.293 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-15 18:27:13.295 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-15 18:27:13.297 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-15 18:27:13.299 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-15 18:27:13.301 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-15 18:27:13.303 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-15 18:27:13.305 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-15 18:27:13.311 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-15 18:27:13.313 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-15 18:27:13.315 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-15 18:27:13.318 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-15 18:27:13.320 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-15 18:27:13.322 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-15 18:27:13.325 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-15 18:27:13.327 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - size = '1'
2016-10-15 18:27:13.329 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_6_1 is 00000006
2016-10-15 18:27:13.332 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000006>>FFFFFFFF
2016-10-15 18:27:13.334 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000006>>FFFFFFFF
2016-10-15 18:27:13.337 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_6_1 is 00000006 [6]
2016-10-15 18:27:13.347 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-15 18:27:13.357 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-15 18:27:13.358 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-15 18:27:13.359 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'

schmidmuc · October 15, 2016, 4:31pm

Part 2

 2016-10-15 18:27:13.360 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
    2016-10-15 18:27:13.361 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
    2016-10-15 18:27:13.362 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
    2016-10-15 18:27:13.363 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
    2016-10-15 18:27:13.364 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
    2016-10-15 18:27:13.365 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
    2016-10-15 18:27:13.366 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
    2016-10-15 18:27:13.372 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
    2016-10-15 18:27:13.379 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
    2016-10-15 18:27:13.382 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
    2016-10-15 18:27:13.385 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
    2016-10-15 18:27:13.388 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
    2016-10-15 18:27:13.390 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
    2016-10-15 18:27:13.393 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
    2016-10-15 18:27:13.395 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
    2016-10-15 18:27:13.398 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
    2016-10-15 18:27:13.400 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
    2016-10-15 18:27:13.402 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
    2016-10-15 18:27:13.403 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
    2016-10-15 18:27:13.409 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
    2016-10-15 18:27:13.411 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
    2016-10-15 18:27:13.413 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
    2016-10-15 18:27:13.415 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
    2016-10-15 18:27:13.417 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
    2016-10-15 18:27:13.419 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - size = '1'
    2016-10-15 18:27:13.421 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_7_1 is 00000004
    2016-10-15 18:27:13.424 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000004>>FFFFFFFF
    2016-10-15 18:27:13.426 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000004>>FFFFFFFF
    2016-10-15 18:27:13.429 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_7_1 is 00000004 [4]
    2016-10-15 18:27:13.431 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
    2016-10-15 18:27:13.436 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
    2016-10-15 18:27:13.440 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
    2016-10-15 18:27:13.442 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
    2016-10-15 18:27:13.444 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
    2016-10-15 18:27:13.446 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
    2016-10-15 18:27:13.448 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
    2016-10-15 18:27:13.450 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
    2016-10-15 18:27:13.451 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
    2016-10-15 18:27:13.453 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
    2016-10-15 18:27:13.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
    2016-10-15 18:27:13.457 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
    2016-10-15 18:27:13.459 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
    2016-10-15 18:27:13.461 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
    2016-10-15 18:27:13.463 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
    2016-10-15 18:27:13.465 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
    2016-10-15 18:27:13.468 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
    2016-10-15 18:27:13.469 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
    2016-10-15 18:27:13.471 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
    2016-10-15 18:27:13.473 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
    2016-10-15 18:27:13.475 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
    2016-10-15 18:27:13.478 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
    2016-10-15 18:27:13.480 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
    2016-10-15 18:27:13.482 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
    2016-10-15 18:27:13.484 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
    2016-10-15 18:27:13.487 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
    2016-10-15 18:27:13.489 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
    2016-10-15 18:27:13.506 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
    2016-10-15 18:27:13.510 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
    2016-10-15 18:27:13.513 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
    2016-10-15 18:27:13.516 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update config_20_1 to 30
    2016-10-15 18:27:13.517 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
    2016-10-15 18:27:13.518 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_SET
    2016-10-15 18:27:13.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
    2016-10-15 18:27:13.521 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.522 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_GET
    2016-10-15 18:27:13.522 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
    2016-10-15 18:27:13.524 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.524 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
    2016-10-15 18:27:13.525 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update config_21_1 to 1
    2016-10-15 18:27:13.591 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_SET
    2016-10-15 18:27:13.541 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
    2016-10-15 18:27:13.593 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.594 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_GET
    2016-10-15 18:27:13.595 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.596 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update config_22_1 to 1
    2016-10-15 18:27:13.597 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_SET
    2016-10-15 18:27:13.599 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.600 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_GET
    2016-10-15 18:27:13.601 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.602 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update config_11_1 to 12
    2016-10-15 18:27:13.603 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_SET
    2016-10-15 18:27:13.604 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
    2016-10-15 18:27:13.615 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
    2016-10-15 18:27:13.618 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.619 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_GET
    2016-10-15 18:27:13.621 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.622 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update config_10_1 to 12
    2016-10-15 18:27:13.623 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_SET
    2016-10-15 18:27:13.625 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.625 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
    2016-10-15 18:27:13.633 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 9: Creating new message for application command CONFIGURATIONCMD_GET
    2016-10-15 18:27:13.634 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
    2016-10-15 18:27:13.642 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
    2016-10-15 18:27:13.635 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 9: Putting message SendData in wakeup queue.
    2016-10-15 18:27:13.647 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Configuration update group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f to [node_1_0]
    2016-10-15 18:27:13.650 [INFO ] [ome.io.rest.core.thing.ThingResource] - Received HTTP PUT request for update config at 'things/zwave:device:ef20d771:node9/config' for the unknown thing 'zwave:device:ef20d771:node9'.
    2016-10-15 18:27:13.650 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
    2016-10-15 18:27:13.659 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
    2016-10-15 18:27:13.667 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
    2016-10-15 18:27:13.673 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
    2016-10-15 18:27:13.674 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
    2016-10-15 18:27:13.677 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
    2016-10-15 18:27:13.681 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
    2016-10-15 18:27:13.683 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
    2016-10-15 18:27:13.686 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
    2016-10-15 18:27:13.688 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
    2016-10-15 18:27:13.700 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
    2016-10-15 18:27:13.704 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
    2016-10-15 18:27:13.707 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - size = '1'
    2016-10-15 18:27:13.719 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_8_1 is 00000003
    2016-10-15 18:27:13.724 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000003>>FFFFFFFF
    2016-10-15 18:27:13.730 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000003>>FFFFFFFF
    2016-10-15 18:27:13.732 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_8_1 is 00000003 [3]
    2016-10-15 18:27:13.733 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
    2016-10-15 18:27:13.735 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
    2016-10-15 18:27:13.738 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
    2016-10-15 18:27:13.740 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
    2016-10-15 18:27:13.742 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
    2016-10-15 18:27:13.745 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
    2016-10-15 18:27:13.747 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
    2016-10-15 18:27:13.754 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
    2016-10-15 18:27:13.755 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
    2016-10-15 18:27:13.757 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
    2016-10-15 18:27:13.758 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
    2016-10-15 18:27:13.759 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
    2016-10-15 18:27:13.761 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
    2016-10-15 18:27:13.762 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
    2016-10-15 18:27:13.763 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
    2016-10-15 18:27:13.765 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
    2016-10-15 18:27:13.767 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
    2016-10-15 18:27:13.768 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
    2016-10-15 18:27:13.770 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
    2016-10-15 18:27:13.772 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
    2016-10-15 18:27:13.773 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
    2016-10-15 18:27:13.775 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
    2016-10-15 18:27:13.777 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
    2016-10-15 18:27:13.779 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
    2016-10-15 18:27:13.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
    2016-10-15 18:27:13.782 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
    2016-10-15 18:27:13.784 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
    2016-10-15 18:27:13.785 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
    2016-10-15 18:27:13.787 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
    2016-10-15 18:27:13.788 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
    2016-10-15 18:27:13.789 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
    2016-10-15 18:27:13.790 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
    2016-10-15 18:27:13.792 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
    2016-10-15 18:27:13.793 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
    2016-10-15 18:27:13.795 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
    2016-10-15 18:27:13.797 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
    2016-10-15 18:27:13.798 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
    2016-10-15 18:27:13.800 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
    2016-10-15 18:27:13.801 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
    2016-10-15 18:27:13.803 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
    2016-10-15 18:27:13.804 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
    2016-10-15 18:27:13.806 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
    2016-10-15 18:27:13.808 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
    2016-10-15 18:27:13.809 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
    2016-10-15 18:27:13.810 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
    2016-10-15 18:27:13.812 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
    2016-10-15 18:27:13.814 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
    2016-10-15 18:27:13.816 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
    2016-10-15 18:27:13.817 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
    2016-10-15 18:27:13.818 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
    2016-10-15 18:27:13.819 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
    2016-10-15 18:27:13.821 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
    2016-10-15 18:27:13.823 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - size = '1'
    2016-10-15 18:27:13.825 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_9_1 is 00000004
    2016-10-15 18:27:13.827 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000004>>FFFFFFFF
    2016-10-15 18:27:13.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000004>>FFFFFFFF
    2016-10-15 18:27:13.830 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_9_1 is 00000004 [4]
    2016-10-15 18:27:13.832 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
    2016-10-15 18:27:13.833 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
    2016-10-15 18:27:13.835 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
    2016-10-15 18:27:13.837 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
    2016-10-15 18:27:13.839 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
    2016-10-15 18:27:13.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
    2016-10-15 18:27:13.842 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
    2016-10-15 18:27:13.844 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
    2016-10-15 18:27:13.845 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
    2016-10-15 18:27:13.857 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
    2016-10-15 18:27:13.860 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
    2016-10-15 18:27:13.861 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
    2016-10-15 18:27:13.862 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
    2016-10-15 18:27:13.863 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
    2016-10-15 18:27:13.865 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
    2016-10-15 18:27:13.866 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
    2016-10-15 18:27:13.867 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
    2016-10-15 18:27:13.869 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
    2016-10-15 18:27:13.870 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
    2016-10-15 18:27:13.872 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
    2016-10-15 18:27:13.873 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
    2016-10-15 18:27:13.874 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
    2016-10-15 18:27:13.876 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
    2016-10-15 18:27:13.878 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
    2016-10-15 18:27:13.879 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
    2016-10-15 18:27:13.881 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
    2016-10-15 18:27:13.883 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
    2016-10-15 18:27:13.884 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
    2016-10-15 18:27:13.886 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
    2016-10-15 18:27:13.887 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
    2016-10-15 18:27:13.889 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
    2016-10-15 18:27:13.891 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - size = '1'
    2016-10-15 18:27:13.893 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_10_1 is 0000000C
    2016-10-15 18:27:13.894 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  0000000C>>FFFFFFFF
    2016-10-15 18:27:13.896 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 0000000C>>FFFFFFFF
    2016-10-15 18:27:13.898 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_10_1 is 0000000C [12]
    2016-10-15 18:27:13.899 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
    2016-10-15 18:27:13.901 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
    2016-10-15 18:27:13.902 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
    2016-10-15 18:27:13.904 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
    2016-10-15 18:27:13.906 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
    2016-10-15 18:27:13.907 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
    2016-10-15 18:27:13.909 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
    2016-10-15 18:27:13.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
    2016-10-15 18:27:13.912 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
    2016-10-15 18:27:13.914 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
    2016-10-15 18:27:13.916 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
    2016-10-15 18:27:13.918 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
    2016-10-15 18:27:13.919 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
    2016-10-15 18:27:13.921 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
    2016-10-15 18:27:13.922 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
    2016-10-15 18:27:13.923 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
    2016-10-15 18:27:13.925 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
    2016-10-15 18:27:13.927 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
    2016-10-15 18:27:13.928 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
    2016-10-15 18:27:13.930 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3

schmidmuc · October 15, 2016, 4:32pm

Part 3

2016-10-15 18:27:13.931 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-15 18:27:13.932 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-15 18:27:13.934 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-15 18:27:13.935 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-15 18:27:13.937 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-15 18:27:13.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-15 18:27:13.940 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-15 18:27:13.942 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-15 18:27:13.944 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-15 18:27:13.946 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-15 18:27:13.948 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-15 18:27:13.950 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-15 18:27:13.951 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-15 18:27:13.953 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-15 18:27:13.954 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-15 18:27:13.956 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-15 18:27:13.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-15 18:27:13.959 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-15 18:27:13.960 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-15 18:27:13.962 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-15 18:27:13.963 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-15 18:27:13.965 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-15 18:27:13.967 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-15 18:27:13.968 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-15 18:27:13.979 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-15 18:27:13.980 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-15 18:27:13.984 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - size = '1'
2016-10-15 18:27:13.988 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_11_1 is 0000000C
2016-10-15 18:27:13.991 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  0000000C>>FFFFFFFF
2016-10-15 18:27:14.007 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 0000000C>>FFFFFFFF
2016-10-15 18:27:14.009 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_11_1 is 0000000C [12]
2016-10-15 18:27:14.010 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-15 18:27:14.012 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-15 18:27:14.014 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-15 18:27:14.016 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
2016-10-15 18:27:14.027 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-15 18:27:14.029 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-15 18:27:14.031 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-15 18:27:14.033 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
2016-10-15 18:27:14.035 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-15 18:27:14.043 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-15 18:27:14.045 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-15 18:27:14.057 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-15 18:27:14.058 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-15 18:27:14.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-15 18:27:14.062 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-15 18:27:14.063 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-15 18:27:14.065 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-15 18:27:14.076 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-15 18:27:14.078 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-15 18:27:14.079 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-15 18:27:14.080 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-15 18:27:14.081 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-15 18:27:14.082 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-15 18:27:14.083 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-15 18:27:14.086 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-15 18:27:14.088 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-15 18:27:14.199 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-15 18:27:14.201 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-15 18:27:14.203 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-15 18:27:14.206 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-15 18:27:14.208 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-15 18:27:14.210 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-15 18:27:14.230 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-15 18:27:14.237 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-15 18:27:14.239 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-15 18:27:14.241 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-15 18:27:14.249 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-15 18:27:14.250 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-15 18:27:14.252 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-15 18:27:14.296 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-15 18:27:14.297 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-15 18:27:14.298 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-15 18:27:14.300 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-15 18:27:14.301 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-15 18:27:14.302 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-15 18:27:14.304 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-15 18:27:14.305 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-15 18:27:14.307 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-15 18:27:14.327 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-15 18:27:14.328 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-15 18:27:14.329 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-15 18:27:14.330 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-15 18:27:14.331 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
2016-10-15 18:27:14.332 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-15 18:27:14.333 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-15 18:27:14.334 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-15 18:27:14.335 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
2016-10-15 18:27:14.337 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-15 18:27:14.338 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-15 18:27:14.339 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-15 18:27:14.341 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-15 18:27:14.342 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-15 18:27:14.343 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-15 18:27:14.345 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-15 18:27:14.346 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-15 18:27:14.347 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-15 18:27:14.349 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-15 18:27:14.350 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-15 18:27:14.352 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-15 18:27:14.353 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-15 18:27:14.355 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-15 18:27:14.357 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-15 18:27:14.358 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-15 18:27:14.359 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - size = '1'
2016-10-15 18:27:14.361 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_12_1 is 0000001E
2016-10-15 18:27:14.362 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  0000001E>>FFFFFFFF
2016-10-15 18:27:14.364 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 0000001E>>FFFFFFFF
2016-10-15 18:27:14.365 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_12_1 is 0000001E [30]
2016-10-15 18:27:14.366 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-15 18:27:14.368 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-15 18:27:14.369 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-15 18:27:14.371 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-15 18:27:14.372 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-15 18:27:14.374 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-15 18:27:14.375 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-15 18:27:14.376 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-15 18:27:14.377 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-15 18:27:14.379 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-15 18:27:14.380 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-15 18:27:14.382 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-15 18:27:14.383 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-15 18:27:14.384 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-15 18:27:14.386 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-15 18:27:14.387 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-15 18:27:14.388 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-15 18:27:14.390 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-15 18:27:14.391 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-15 18:27:14.392 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-15 18:27:14.394 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-15 18:27:14.395 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-15 18:27:14.396 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-15 18:27:14.397 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-15 18:27:14.399 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-15 18:27:14.400 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-15 18:27:14.402 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-15 18:27:14.403 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-15 18:27:14.404 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
2016-10-15 18:27:14.406 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-15 18:27:14.407 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-15 18:27:14.408 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-15 18:27:14.410 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
2016-10-15 18:27:14.411 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-15 18:27:14.412 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-15 18:27:14.413 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-15 18:27:14.414 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-15 18:27:14.416 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-15 18:27:14.417 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-15 18:27:14.419 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-15 18:27:14.420 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-15 18:27:14.421 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-15 18:27:14.423 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-15 18:27:14.424 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-15 18:27:14.425 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-15 18:27:14.427 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-15 18:27:14.428 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-15 18:27:14.429 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-15 18:27:14.431 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-15 18:27:14.432 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-15 18:27:14.433 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-15 18:27:14.434 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-15 18:27:14.436 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-15 18:27:14.438 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-15 18:27:14.439 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-15 18:27:14.440 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - size = '1'
2016-10-15 18:27:14.442 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_13_1 is 00000006
2016-10-15 18:27:14.443 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  00000006>>FFFFFFFF
2016-10-15 18:27:14.445 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 00000006>>FFFFFFFF
2016-10-15 18:27:14.446 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_13_1 is 00000006 [6]
2016-10-15 18:27:14.448 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-15 18:27:14.449 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-15 18:27:14.450 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-15 18:27:14.452 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-15 18:27:14.453 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-15 18:27:14.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'
2016-10-15 18:27:14.456 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Config about to update 20 parameters...
2016-10-15 18:27:14.457 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1
2016-10-15 18:27:14.459 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 len=3
2016-10-15 18:27:14.460 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - id = '20'
2016-10-15 18:27:14.461 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_20_1 - size = '1'
2016-10-15 18:27:14.463 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter config_20_1 is 0000001E
2016-10-15 18:27:14.464 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Pre-processing  0000001E>>FFFFFFFF
2016-10-15 18:27:14.466 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Post-processing 0000001E>>FFFFFFFF
2016-10-15 18:27:14.467 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Sub-parameter setting config_20_1 is 0000001E [30]
2016-10-15 18:27:14.469 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1
2016-10-15 18:27:14.470 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 len=3
2016-10-15 18:27:14.472 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_21_1 - id = '21'
2016-10-15 18:27:14.473 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1
2016-10-15 18:27:14.474 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 len=3
2016-10-15 18:27:14.475 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_22_1 - id = '22'
2016-10-15 18:27:14.477 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1
2016-10-15 18:27:14.478 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 len=3
2016-10-15 18:27:14.479 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_11_1 - id = '11'
2016-10-15 18:27:14.480 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1
2016-10-15 18:27:14.481 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 len=3
2016-10-15 18:27:14.483 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_10_1 - id = '10'
2016-10-15 18:27:14.484 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@194df7f
2016-10-15 18:27:14.486 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_interval
2016-10-15 18:27:14.487 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_1
2016-10-15 18:27:14.489 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_2
2016-10-15 18:27:14.490 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing group_org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@28f424
2016-10-15 18:27:14.492 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1
2016-10-15 18:27:14.493 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 len=3
2016-10-15 18:27:14.494 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_2_1 - id = '2'
2016-10-15 18:27:14.496 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing wakeup_node
2016-10-15 18:27:14.497 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1
2016-10-15 18:27:14.498 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 len=3
2016-10-15 18:27:14.500 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_7_1 - id = '7'
2016-10-15 18:27:14.501 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1
2016-10-15 18:27:14.503 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 len=3
2016-10-15 18:27:14.504 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_8_1 - id = '8'
2016-10-15 18:27:14.505 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1
2016-10-15 18:27:14.507 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 len=3
2016-10-15 18:27:14.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_9_1 - id = '9'
2016-10-15 18:27:14.509 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1
2016-10-15 18:27:14.511 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 len=3
2016-10-15 18:27:14.512 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_12_1 - id = '12'
2016-10-15 18:27:14.513 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1
2016-10-15 18:27:14.514 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 len=3
2016-10-15 18:27:14.515 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_4_1 - id = '4'
2016-10-15 18:27:14.517 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1
2016-10-15 18:27:14.518 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 len=3
2016-10-15 18:27:14.519 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_13_1 - id = '13'
2016-10-15 18:27:14.520 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1
2016-10-15 18:27:14.522 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 len=3
2016-10-15 18:27:14.523 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_5_1 - id = '5'
2016-10-15 18:27:14.525 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1
2016-10-15 18:27:14.526 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 len=3
2016-10-15 18:27:14.528 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Processing config_6_1 - id = '6'

schmidmuc · October 15, 2016, 4:35pm

In addition a log file of closing the door contact:

2016-10-15 18:34:22.589 [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 06 06 31 05 01 22 01 13 03 
2016-10-15 18:34:22.608 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-15 18:34:22.613 [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 06 06 31 05 01 22 01 13 03 
2016-10-15 18:34:22.618 [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 06 06 31 05 01 22 01 13 03 
2016-10-15 18:34:22.623 [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 06 06 31 05 01 22 01 13 
2016-10-15 18:34:22.625 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-15 18:34:22.627 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-15 18:34:22.629 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@f85a9 already registered
2016-10-15 18:34:22.632 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-15 18:34:22.634 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-15 18:34:22.636 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class BATTERY
2016-10-15 18:34:22.638 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 18:34:22.639 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Received Battery Request
2016-10-15 18:34:22.641 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Battery report value = 100
2016-10-15 18:34:22.643 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-15 18:34:22.644 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-15 18:34:22.646 [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 18:34:22.648 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:battery-level to 100 [DecimalType]
2016-10-15 18:34:22.663 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class ALARM
2016-10-15 18:34:22.670 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 18:34:22.672 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Received ALARM command V4
2016-10-15 18:34:22.674 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Process NOTIFICATION_REPORT V4
2016-10-15 18:34:22.676 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: NOTIFICATION report - 0 = 0, event=23, status=255
2016-10-15 18:34:22.677 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm Type = ACCESS_CONTROL (0)
2016-10-15 18:34:22.679 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
2016-10-15 18:34:22.681 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2016-10-15 18:34:22.683 [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 18:34:22.685 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_door to OPEN [OpenClosedType]
2016-10-15 18:34:22.698 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-15 18:34:22.699 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 18:34:22.700 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-15 18:34:22.706 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-15 18:34:22.708 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-15 18:34:22.709 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 6
2016-10-15 18:34:22.710 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:34:22.711 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:34:22.712 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 6
2016-10-15 18:34:22.713 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_luminance to 6 [DecimalType]
2016-10-15 18:34:22.733 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-15 18:34:22.741 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 18:34:22.746 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-15 18:34:22.748 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-15 18:34:22.752 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-15 18:34:22.753 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 27.5
2016-10-15 18:34:22.758 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:34:22.767 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:34:22.768 [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 18:34:22.770 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 9: Sensor is reporting scale 0, requiring conversion to 0. Value is now 27.5.
2016-10-15 18:34:22.771 [DEBUG] [converter.ZWaveCommandClassConverter] - Converted temperature from 27.5C to 27.5C
2016-10-15 18:34:22.772 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_temperature to 27.5 [DecimalType]

schmidmuc · October 15, 2016, 4:36pm

And one of opening the contact again:

2016-10-15 18:35:24.920 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 255: Transaction not completed: node address inconsistent.  lastSent=255, incoming=255
2016-10-15 18:35:33.035 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 09 0A 8F 01 01 06 31 05 01 22 01 1D 6A 
2016-10-15 18:35:33.040 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-15 18:35:33.042 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 09 0A 8F 01 01 06 31 05 01 22 01 1D 6A 
2016-10-15 18:35:33.044 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 09 0A 8F 01 01 06 31 05 01 22 01 1D 6A 
2016-10-15 18:35:33.046 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 09 0A 8F 01 01 06 31 05 01 22 01 1D 
2016-10-15 18:35:33.055 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-15 18:35:33.057 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-15 18:35:33.058 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@f85a9 already registered
2016-10-15 18:35:33.060 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-15 18:35:33.061 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-15 18:35:33.062 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-15 18:35:33.062 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 18:35:33.063 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-15 18:35:33.064 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-15 18:35:33.065 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-15 18:35:33.066 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 28.5
2016-10-15 18:35:33.067 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:35:33.068 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:35:33.069 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 28.5
2016-10-15 18:35:33.070 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 9: Sensor is reporting scale 0, requiring conversion to 0. Value is now 28.5.
2016-10-15 18:35:33.071 [DEBUG] [converter.ZWaveCommandClassConverter] - Converted temperature from 28.5C to 28.5C
2016-10-15 18:35:33.072 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_temperature to 28.5 [DecimalType]
2016-10-15 18:35:33.079 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 255: Transaction not completed: node address inconsistent.  lastSent=255, incoming=255
2016-10-15 18:35:34.221 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 09 03 20 01 FF 26 
2016-10-15 18:35:34.224 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-15 18:35:34.227 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 09 03 20 01 FF 26 
2016-10-15 18:35:34.229 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 09 03 20 01 FF 26 
2016-10-15 18:35:34.230 [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 18:35:34.232 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-15 18:35:34.233 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-15 18:35:34.234 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@f85a9 already registered
2016-10-15 18:35:34.235 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class BASIC
2016-10-15 18:35:34.237 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Received Basic Request
2016-10-15 18:35:34.238 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic Set sent to the controller will be processed as Basic Report
2016-10-15 18:35:34.239 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 9: Basic report, value = 0xFF
2016-10-15 18:35:34.241 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-15 18:35:34.242 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-15 18:35:34.243 [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 18:35:34.244 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_binary to ON [OnOffType]
2016-10-15 18:35:34.249 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 255: Transaction not completed: node address inconsistent.  lastSent=255, incoming=255
2016-10-15 18:35:34.297 [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 07 06 31 05 01 22 01 1D 0D 
2016-10-15 18:35:34.321 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-15 18:35:34.332 [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 07 06 31 05 01 22 01 1D 0D 
2016-10-15 18:35:34.339 [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 07 06 31 05 01 22 01 1D 0D 
2016-10-15 18:35:34.348 [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 07 06 31 05 01 22 01 1D 
2016-10-15 18:35:34.356 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Application Command Request (ALIVE:DONE)
2016-10-15 18:35:34.362 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2016-10-15 18:35:34.366 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@f85a9 already registered
2016-10-15 18:35:34.368 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Incoming command class MULTI_CMD
2016-10-15 18:35:34.372 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Received Multi-Command Request
2016-10-15 18:35:34.375 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class BATTERY
2016-10-15 18:35:34.377 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 18:35:34.378 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Received Battery Request
2016-10-15 18:35:34.379 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 9: Battery report value = 100
2016-10-15 18:35:34.380 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-10-15 18:35:34.382 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-10-15 18:35:34.383 [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 18:35:34.384 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:battery-level to 100 [DecimalType]
2016-10-15 18:35:34.399 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class ALARM
2016-10-15 18:35:34.400 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 18:35:34.401 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Received ALARM command V4
2016-10-15 18:35:34.402 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Process NOTIFICATION_REPORT V4
2016-10-15 18:35:34.404 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: NOTIFICATION report - 0 = 0, event=22, status=255
2016-10-15 18:35:34.405 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 9: Alarm Type = ACCESS_CONTROL (0)
2016-10-15 18:35:34.412 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
2016-10-15 18:35:34.415 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2016-10-15 18:35:34.416 [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 18:35:34.417 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_door to OPEN [OpenClosedType]
2016-10-15 18:35:34.423 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-15 18:35:34.424 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 18:35:34.425 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-15 18:35:34.426 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-15 18:35:34.428 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Luminance(3), Scale = 0
2016-10-15 18:35:34.430 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 7
2016-10-15 18:35:34.433 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:35:34.435 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:35:34.436 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 7
2016-10-15 18:35:34.438 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_luminance to 7 [DecimalType]
2016-10-15 18:35:34.443 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Incoming command class SENSOR_MULTILEVEL
2016-10-15 18:35:34.444 [DEBUG] [dclass.ZWaveMultiCommandCommandClass] - NODE 9: Calling handleApplicationCommandRequest.
2016-10-15 18:35:34.446 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5
2016-10-15 18:35:34.447 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Multi Level REPORT received
2016-10-15 18:35:34.449 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Type = Temperature(1), Scale = 0
2016-10-15 18:35:34.450 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 9: Sensor Value = 28.5
2016-10-15 18:35:34.451 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:35:34.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2016-10-15 18:35:34.467 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 28.5
2016-10-15 18:35:34.469 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 9: Sensor is reporting scale 0, requiring conversion to 0. Value is now 28.5.
2016-10-15 18:35:34.470 [DEBUG] [converter.ZWaveCommandClassConverter] - Converted temperature from 28.5C to 28.5C
2016-10-15 18:35:34.472 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating channel state zwave:device:ef20d771:node9:sensor_temperature to 28.5 [DecimalType]
2016-10-15 18:35:34.480 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 255: Transaction not completed: node address inconsistent.  lastSent=255, incoming=255
2016-10-15 18:35:35.321 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 12 00 04 00 08 0C 60 0D 03 03 31 05 01 44 00 00 09 F6 0E 
2016-10-15 18:35:35.325 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-10-15 18:35:35.328 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 12 00 04 00 08 0C 60 0D 03 03 31 05 01 44 00 00 09 F6 0E 
2016-10-15 18:35:35.330 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 12 00 04 00 08 0C 60 0D 03 03 31 05 01 44 00 00 09 F6 0E 
2016-10-15 18:35:35.332 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 08 0C 60 0D 03 03 31 05 01 44 00 00 09 F6 
2016-10-15 18:35:35.333 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 8: Application Command Request (ALIVE:DONE)

chris · October 15, 2016, 4:42pm

Thanks - it didn’t need to be reincluded - just the thing needed to be deleted and added again so that the binding picks up the latest thing definition from the system.

When you do a door open/closed again, what does the log show?

schmidmuc · October 15, 2016, 4:44pm

Oh ok… Anyway, this way it was really added again

I already posted log file parts during closing / opening the door

chris · October 15, 2016, 4:45pm

Sure - but I want to see if there’s anything different after I updated the binding. In theory there should be information about the processing of the message now.

Just a short log showing one open or close should be enough.

schmidmuc · October 15, 2016, 5:53pm

So you need a newer log than the one I posted one hour ago?