This page last changed on Dec 22, 2014 by aleksey_z.

Is there support for devices such manufacturer as ConnectHome.
Interested in working thermostat: http://connecthome.com.ua/product/CH201


zwcommand.jpg (image/jpeg)
orlog.jpg (image/jpeg)
orlog.jpg (image/jpeg)
szw dashb.jpg (image/jpeg)
device.jpg (image/jpeg)
device propet.jpg (image/jpeg)
device assoc.jpg (image/jpeg)
prop.jpg (image/jpeg)
switched off.jpg (image/jpeg)
SetTempSP.jpg (image/jpeg)
StatusTempSP.jpg (image/jpeg)
TempSP range Sensor.jpg (image/jpeg)
TempSPSensor.jpg (image/jpeg)
termostat slider.jpg (image/jpeg)
screen.jpg (image/jpeg)
ch201.jpg (image/jpeg)
orlog.jpg (image/jpeg)
ORtempSensors.jpg (image/jpeg)
ORtempSensors1.jpg (image/jpeg)
ORtempSensors.jpg (image/jpeg)
proptstatSynZW.jpg (image/jpeg)
domdev.jpg (image/jpeg)

Looks like a basic Zwave switch to me, so that should work. To tell for sure one would need to know the Zwave command classes that the device supports. Then I could say more about temperature setting/reading.
Links to manuals on that page don't work for me. Also google translate to english is not "flawless".
I have seen something from qubino, which resembles this device.

Posted by pz1 at Dec 05, 2014 14:08

Thank you for having responded.
Information found only in Russian, manual
Said that the working temperature is adjusted from UI (in Fibaro).

Posted by aleksey_z at Dec 05, 2014 15:26

Well, as far as I understood the translated Russian manual, you will need a tool to make configuration settings. Afaik, those are not available in the present OpenRemote implementation of Z-Wave. Unfortunately the public Z-wave.me Z-Cloud service which supported such settings has been discontinued this spring.

Posted by pz1 at Dec 05, 2014 15:59

And if use SynoZWave to make configuration settings?

Posted by aleksey_z at Dec 05, 2014 18:22

Sorry, I have no recent experience with that. As it is free, you may give it a try

Posted by pz1 at Dec 05, 2014 18:34

Yes, get the thermostat, I'll try.
Here wrote that the support will be soon. http://www.openremote.org/display/forums/Fibaro+FGS-221+2x+1.5kW+pairing?focusedCommentId=23594237#comment-23594237

Posted by aleksey_z at Dec 05, 2014 18:39

They have been announcing Z-Wave extensions for almost two years now. The meaning of "soon" is very flexible

Posted by pz1 at Dec 05, 2014 18:56

It's unfortunate that i am have to use Openremote for to manage SynoZWave. Use are two applications.

Posted by aleksey_z at Dec 05, 2014 19:10

Good afternoon.
Got a thermostat and still some devices. Will I be able to connect and use their full-featured, or in the case of FGS-221?

Connect Home thermostat CH-201
Fibaro RGBW Controller FGRGB-101
Fibaro Motion Sensor FGMS-001
Fibaro Smoke Sensor FGSS-101
Vision Security Door/Window Sensor VIS_ZD2102
Aeon Labs Energie meter AEO_HEM1

Posted by aleksey_z at Dec 16, 2014 13:35

The new Z-Wave implementation (free version will be released very soon) was tested with the following devices that you've listed:

  • Fibaro Double Relay Switch FGS-221 (including multi instance associations)
  • Fibaro Motion Sensor FGMS-001
  • Fibaro Smoke Sensor FGSS-001

The following devices should also work (no guarantee) because almost all required Z-Wave command classes were implemented:

  • Fibaro RGBW Controller FGRFG-101
  • Aeon Labs Energie Meter AEO_HEM1
  • Vision Security Door/Window Sensor VIS_ZD2102

The 'Alarm' command class of the Vision Security Door/Window Sensor VIS_ZD2102 is currently not supported (there is currently only support for the 'Alarm Sensor' command class) but basic binary sensor functionality should work.

Thermostats are currently not supported - we will add thermostats in a next step.

Posted by rhitz at Dec 16, 2014 19:32

to RAINER HITZ

Thank you for your response.
Could you elaborate, "(free version will be released very soon)" - this is when?

"Thermostats are currently not supported - we will add thermostats in a next step." - Then do not quite understand: http://www.openremote.org/display/docs/Designer+2.0+-+Available+Z-Wave+Commands
I can not control the thermostat?

Posted by aleksey_z at Dec 17, 2014 09:35

to PZ1

"Looks like a basic Zwave switch to me, so that should work. To tell for sure one would need to know the Zwave command classes that the device supports. Then I could say more about temperature setting/reading." (c)

Received from the manufacturer ConnectHome, command classes to the thermostat CH201.

THERMOSTAT_MODE
THERMOSTAT_SETPOINT
THERMOSTAT_OPERATING_STATE
BASIC
SENSOR_MULTILEVEL
MULTI_CHANNEL
ASSOSIATIONS
MULTI_CHANNEL_ASSOSIATIONS
CONFIGURATION

Posted by aleksey_z at Dec 17, 2014 09:48

At the time when I still used OR Zwave, these Thermostat commands did function with my Danfoss Living Connect (old pre-LC13 version thermostat).

Posted by pz1 at Dec 17, 2014 09:56

The command classes do resemble those of StellaZ Radiator Thermostatic Valve. Thermostat_Mode is important. You should ask the manufacturer for the different modes you can set (parameter and meaning) in this device.
My knowledge about the Basic command is a bit rusty, but maybe you can use that to set Thermostat_Mode as well. I does for the StellaZ. Don't remember if OR supports Basic command

Posted by pz1 at Dec 17, 2014 10:14

I try to connect, and see what will happen in the log OR and SynoZWave.
Can then become clear.

Posted by aleksey_z at Dec 17, 2014 11:52

The thermostat is connected to the Z-Wave Stick. In the OR saw:

In SynoZWave:


The load - the lamp, when you connect the thermostat to 220V immediately switched on. If you heat the fingers temperature sensor, is switched off, cools and switched on again.

Will make friends with the OR the thermostat?

Posted by aleksey_z at Dec 17, 2014 16:13

Here logs SYnoZWave. How to add the thermostat in the OR? What commands are available?

2014-12-18 17:57:06.488 contrlr,     Node 004 - Known
2014-12-18 17:57:06.488 Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe1 live=1
2014-12-18 17:57:06.488 Node004, QueryStage_Probe1
2014-12-18 17:57:06.488 Node004, NoOperation::Set - Routing=true
2014-12-18 17:57:06.488 Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x03, 0xc5
2014-12-18 17:57:06.488 Node004, Queuing (Query) Query Stage Complete (Probe1)

2014-12-18 17:57:06.553 Notification: Node Added Home 0184d9e3 Node 4 Genre basic Class NO OPERATION Instance 0 Index 0 Type bool
2014-12-18 17:57:06.565 Notification: Node Protocol Info Home 0184d9e3 Node 4 Genre basic Class NO OPERATION Instance 0 Index 0 Type bool
2014-12-18 17:57:06.579 Notification: Essential Node 4 Queries Complete
2014-12-18 17:57:06.591 Notification: Value Added Home 0x0184d9e3 Node 4 Genre basic Class BASIC Instance 1 Index 0 Type byte
2014-12-18 17:57:06.591 Notification: Value Added Home 0x0184d9e3 Node 4 Genre basic Class BASIC Instance 2 Index 0 Type byte
2014-12-18 17:57:06.592 Notification: Value Removed Home 0x0184d9e3 Node 4 Genre basic Class BASIC Instance 1 Index 0 Type byte
2014-12-18 17:57:06.592 Notification: Value Added Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 1 Index 1 Type decimal
2014-12-18 17:57:06.592 Notification: Value Added Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 2 Index 1 Type decimal
2014-12-18 17:57:06.592 Notification: Value Added Home 0x0184d9e3 Node 4 Genre user Class THERMOSTAT MODE Instance 1 Index 0 Type list
2014-12-18 17:57:06.592 Notification: Value Added Home 0x0184d9e3 Node 4 Genre user Class THERMOSTAT MODE Instance 2 Index 0 Type list
2014-12-18 17:57:06.592 Notification: Value Added Home 0x0184d9e3 Node 4 Genre user Class THERMOSTAT SETPOINT Instance 1 Index 2 Type decimal
2014-12-18 17:57:06.592 Notification: Value Added Home 0x0184d9e3 Node 4 Genre user Class THERMOSTAT SETPOINT Instance 2 Index 2 Type decimal
2014-12-18 17:57:06.592 Notification: Value Added Home 0x0184d9e3 Node 4 Genre user Class ALARM Instance 1 Index 0 Type byte
2014-12-18 17:57:06.592 Notification: Value Added Home 0x0184d9e3 Node 4 Genre user Class ALARM Instance 1 Index 1 Type byte
2014-12-18 17:57:06.592 Notification: Group Home 0x0184d9e3 Node 4 Group 1
2014-12-18 17:57:06.592 Notification: Group Home 0x0184d9e3 Node 4 Group 2
2014-12-18 17:57:06.593 Notification: Value Added Home 0x0184d9e3 Node 4 Genre system Class VERSION Instance 1 Index 0 Type string
2014-12-18 17:57:06.593 Notification: Value Added Home 0x0184d9e3 Node 4 Genre system Class VERSION Instance 1 Index 1 Type string
2014-12-18 17:57:06.593 Notification: Value Added Home 0x0184d9e3 Node 4 Genre system Class VERSION Instance 1 Index 2 Type string
2014-12-18 17:57:06.593 Notification: Node Naming Home 0184d9e3 Node 4 Genre basic Class NO OPERATION Instance 0 Index 0 Type bool

2014-12-18 17:57:06.656 Node004, Sending (NoOp) message (Callback ID=0x03, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x03, 0xc5
2014-12-18 17:57:06.662 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:06.662 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:06.677 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x03, 0x00, 0xea
2014-12-18 17:57:06.677 Node004,   ZW_SEND_DATA Request with callback ID 0x03 received (expected 0x03)
2014-12-18 17:57:06.677 Node004, Request RTT 21 Average Request RTT 21
2014-12-18 17:57:06.677 Node004,   Expected reply was received
2014-12-18 17:57:06.677 Node004,   Message transaction complete
2014-12-18 17:57:06.678 
2014-12-18 17:57:06.678 Node004, Removing current message
2014-12-18 17:57:06.678 Notification: Notification home 0184d9e3 node 4 No Operation Message Complete

2014-12-18 17:57:06.949 Node004, Sending (Command) message (Callback ID=0x06, Expected Reply=0x47) - Deleting return routes (Node=4): 0x01, 0x05, 0x00, 0x47, 0x04, 0x06, 0xbf
2014-12-18 17:57:06.955 Node004,   Received: 0x01, 0x04, 0x01, 0x47, 0x01, 0xbc
2014-12-18 17:57:06.955 
2014-12-18 17:57:06.956 Node004, Received reply to FUNC_ID_ZW_DELETE_RETURN_ROUTE - command in progress
2014-12-18 17:57:06.956 Node004,   Expected reply was received
2014-12-18 17:57:07.105 Node004,   Received: 0x01, 0x05, 0x00, 0x47, 0x06, 0x00, 0xbb
2014-12-18 17:57:07.105 
2014-12-18 17:57:07.105 Node004, Received reply to FUNC_ID_ZW_DELETE_RETURN_ROUTE for node 4 - SUCCESS
2014-12-18 17:57:07.105 Node004,   Expected callbackId was received
2014-12-18 17:57:07.105 Node004,   Message transaction complete
2014-12-18 17:57:07.105 
2014-12-18 17:57:07.105 Node004, Removing current message
2014-12-18 17:57:07.105 Assigning return route from node 4 to node 1
2014-12-18 17:57:07.105 Node004, Queuing (Command) Assigning return route (Node=4): 0x01, 0x06, 0x00, 0x46, 0x04, 0x01, 0x07, 0xbd
2014-12-18 17:57:07.106 
2014-12-18 17:57:07.106 Node004, Sending (Command) message (Callback ID=0x07, Expected Reply=0x46) - Assigning return route (Node=4): 0x01, 0x06, 0x00, 0x46, 0x04, 0x01, 0x07, 0xbd
2014-12-18 17:57:07.114 Node004,   Received: 0x01, 0x04, 0x01, 0x46, 0x01, 0xbd
2014-12-18 17:57:07.114 
2014-12-18 17:57:07.115 Node004, Received reply to FUNC_ID_ZW_ASSIGN_RETURN_ROUTE - command in progress
2014-12-18 17:57:07.115 Node004,   Expected reply was received
2014-12-18 17:57:07.294 Node004,   Received: 0x01, 0x05, 0x00, 0x46, 0x07, 0x00, 0xbb
2014-12-18 17:57:07.294 
2014-12-18 17:57:07.294 Node004, Received reply to FUNC_ID_ZW_ASSIGN_RETURN_ROUTE for node 4 - SUCCESS
2014-12-18 17:57:07.295 Node004,   Expected callbackId was received
2014-12-18 17:57:07.295 Node004,   Message transaction complete
2014-12-18 17:57:07.295 
2014-12-18 17:57:07.295 Node004, Removing current message

2014-12-18 17:57:07.790 Node004, Query Stage Complete (Probe1)
2014-12-18 17:57:07.790 Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
2014-12-18 17:57:07.790 Node004, QueryStage_Associations
2014-12-18 17:57:07.790 Node004, Number of association groups reported for node 4 is 2.
2014-12-18 17:57:07.790 Node004, Get Associations for group 1 of node 4
2014-12-18 17:57:07.790 Node004, Queuing (Send) Get Associations (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x01, 0x25, 0x10, 0x52
2014-12-18 17:57:07.790 Node004, Queuing (Query) Query Stage Complete (Associations)
2014-12-18 17:57:07.791 
2014-12-18 17:57:07.791 Node004, Sending (Send) message (Callback ID=0x10, Expected Reply=0x04) - Get Associations (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x01, 0x25, 0x10, 0x52
2014-12-18 17:57:07.797 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:07.797 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:07.812 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x10, 0x00, 0xf9
2014-12-18 17:57:07.812 Node004,   ZW_SEND_DATA Request with callback ID 0x10 received (expected 0x10)
2014-12-18 17:57:07.812 Node004, Request RTT 21 Average Request RTT 21
2014-12-18 17:57:07.824 Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x06, 0x85, 0x03, 0x01, 0x0a, 0x00, 0x01, 0x79
2014-12-18 17:57:07.824 
2014-12-18 17:57:07.825 Node004, Response RTT 33 Average Response RTT 33
2014-12-18 17:57:07.825 Node004, Received Association report from node 4, group 1, containing 1 associations
2014-12-18 17:57:07.825 Node004,   The group contains:
2014-12-18 17:57:07.825 Node004,     Node 1
2014-12-18 17:57:07.825 Node004, Get Associations for group 2 of node 4
2014-12-18 17:57:07.825 Node004, Queuing (Send) Get Associations (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x02, 0x25, 0x11, 0x50
2014-12-18 17:57:07.825 Node004,   Expected reply and command class was received
2014-12-18 17:57:07.825 Node004,   Message transaction complete
2014-12-18 17:57:07.825 
2014-12-18 17:57:07.826 Node004, Removing current message
2014-12-18 17:57:07.826 
2014-12-18 17:57:07.826 Node004, Sending (Send) message (Callback ID=0x11, Expected Reply=0x04) - Get Associations (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x02, 0x25, 0x11, 0x50
2014-12-18 17:57:07.832 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:07.832 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:07.847 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x11, 0x00, 0xf8
2014-12-18 17:57:07.847 Node004,   ZW_SEND_DATA Request with callback ID 0x11 received (expected 0x11)
2014-12-18 17:57:07.847 Node004, Request RTT 21 Average Request RTT 21
2014-12-18 17:57:07.859 Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x05, 0x85, 0x03, 0x02, 0x01, 0x00, 0x74
2014-12-18 17:57:07.859 
2014-12-18 17:57:07.859 Node004, Response RTT 33 Average Response RTT 33
2014-12-18 17:57:07.859 Node004, Received Association report from node 4, group 2, containing 0 associations
2014-12-18 17:57:07.860 Node004, Querying associations for node 4 is complete.
2014-12-18 17:57:07.860 Node004,   Expected reply and command class was received
2014-12-18 17:57:07.860 Node004,   Message transaction complete
2014-12-18 17:57:07.860 
2014-12-18 17:57:07.860 Node004, Removing current message
2014-12-18 17:57:07.860 Notification: Group Home 0x0184d9e3 Node 4 Group 2

2014-12-18 17:57:07.875 Node004, Query Stage Complete (Associations)
2014-12-18 17:57:07.875 Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Neighbors live=1
2014-12-18 17:57:07.875 Node004, QueryStage_Neighbors
2014-12-18 17:57:07.875 Requesting routing info (neighbor list) for Node 4
2014-12-18 17:57:07.875 Node004, Queuing (Command) Get Routing Info (Node=4): 0x01, 0x07, 0x00, 0x80, 0x04, 0x00, 0x00, 0x03, 0x7f
2014-12-18 17:57:07.875 Node004, Queuing (Query) Query Stage Complete (Neighbors)
2014-12-18 17:57:07.875 
2014-12-18 17:57:07.876 Node004, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=4): 0x01, 0x07, 0x00, 0x80, 0x04, 0x00, 0x00, 0x03, 0x7f
2014-12-18 17:57:07.881 Node004,   Received: 0x01, 0x20, 0x01, 0x80, 0x07, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x59
2014-12-18 17:57:07.881 
2014-12-18 17:57:07.881 Node004, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2014-12-18 17:57:07.881 Node004,     Neighbors of this node are:
2014-12-18 17:57:07.881 Node004,     Node 1
2014-12-18 17:57:07.882 Node004,     Node 2
2014-12-18 17:57:07.882 Node004,     Node 3
2014-12-18 17:57:07.882 Node004,   Expected reply was received
2014-12-18 17:57:07.882 Node004,   Message transaction complete
2014-12-18 17:57:07.882 
2014-12-18 17:57:07.882 Node004, Removing current message

2014-12-18 17:57:07.883 Node004, Query Stage Complete (Neighbors)
2014-12-18 17:57:07.883 Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2014-12-18 17:57:07.883 Node004, QueryStage_Session
2014-12-18 17:57:07.883 Node004, Queuing (Query) ThermostatSetpointCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x43, 0x02, 0x02, 0x25, 0x16, 0x91
2014-12-18 17:57:07.883 Node004, Queuing (Query) MultiChannel Encapsulated (instance=2): ThermostatSetpointCmd_Get (Node=4): 0x01, 0x0e, 0x00, 0x13, 0x04, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x43, 0x02, 0x02, 0x25, 0x17, 0xfd
2014-12-18 17:57:07.884 Node004, Queuing (Query) Query Stage Complete (Session)

 
2014-12-18 17:57:08.313 Node004, Sending (Query) message (Callback ID=0x16, Expected Reply=0x04) - ThermostatSetpointCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x43, 0x02, 0x02, 0x25, 0x16, 0x91
2014-12-18 17:57:08.318 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:08.318 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:08.334 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x16, 0x00, 0xff
2014-12-18 17:57:08.334 Node004,   ZW_SEND_DATA Request with callback ID 0x16 received (expected 0x16)
2014-12-18 17:57:08.334 Node004, Request RTT 21 Average Request RTT 21
2014-12-18 17:57:08.346 Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x06, 0x43, 0x03, 0x02, 0x02, 0x00, 0x00, 0xb5
2014-12-18 17:57:08.346 
2014-12-18 17:57:08.346 Node004, Response RTT 33 Average Response RTT 33
2014-12-18 17:57:08.346 Node004, Initial read of value
2014-12-18 17:57:08.347 Node004, Received thermostat setpoint report: Setpoint Cooling 1 = 0C
2014-12-18 17:57:08.347 Node004,   Expected reply and command class was received
2014-12-18 17:57:08.347 Node004,   Message transaction complete
2014-12-18 17:57:08.347 
2014-12-18 17:57:08.347 Node004, Removing current message
2014-12-18 17:57:08.347 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class THERMOSTAT SETPOINT Instance 1 Index 2 Type decimal
2014-12-18 17:57:08.362 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 17:57:08.363 
2014-12-18 17:57:08.363 Node004, Sending (Query) message (Callback ID=0x17, Expected Reply=0x04) - MultiChannel Encapsulated (instance=2): ThermostatSetpointCmd_Get (Node=4): 0x01, 0x0e, 0x00, 0x13, 0x04, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x43, 0x02, 0x02, 0x25, 0x17, 0xfd
2014-12-18 17:57:08.369 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:08.369 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:08.385 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x17, 0x00, 0xfe
2014-12-18 17:57:08.385 Node004,   ZW_SEND_DATA Request with callback ID 0x17 received (expected 0x17)
2014-12-18 17:57:08.385 Node004, Request RTT 22 Average Request RTT 21
2014-12-18 17:57:08.398 Node004,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x60, 0x0d, 0x01, 0x01, 0x43, 0x03, 0x02, 0x02, 0x00, 0x00, 0xc8
2014-12-18 17:57:08.398 
2014-12-18 17:57:08.398 Node004, Response RTT 35 Average Response RTT 34
2014-12-18 17:57:08.398 Node004, Received a MultiChannelEncap from node 4, endpoint 1 for Command Class COMMAND_CLASS_THERMOSTAT_SETPOINT
2014-12-18 17:57:08.399 Node004, Initial read of value
2014-12-18 17:57:08.399 Node004, Received thermostat setpoint report: Setpoint Cooling 1 = 0C
2014-12-18 17:57:08.399 Node004,   Expected reply and command class was received
2014-12-18 17:57:08.399 Node004,   Message transaction complete
2014-12-18 17:57:08.399 
2014-12-18 17:57:08.399 Node004, Removing current message
2014-12-18 17:57:08.399 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class THERMOSTAT SETPOINT Instance 2 Index 2 Type decimal
2014-12-18 17:57:08.414 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 17:57:08.415 Node004, Query Stage Complete (Session)
2014-12-18 17:57:08.415 Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=1
2014-12-18 17:57:08.415 Node004, QueryStage_Dynamic
2014-12-18 17:57:08.415 Node004, Queuing (Send) BasicCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x20, 0x02, 0x25, 0x1e, 0xfa
2014-12-18 17:57:08.415 Node004, Queuing (Send) MultiChannel Encapsulated (instance=2): BasicCmd_Get (Node=4): 0x01, 0x0d, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x20, 0x02, 0x25, 0x1f, 0x96
2014-12-18 17:57:08.415 Node004, Queuing (Send) SensorMultilevelCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x25, 0x20, 0xd3
2014-12-18 17:57:08.415 Node004, Queuing (Send) MultiChannel Encapsulated (instance=2): SensorMultilevelCmd_Get (Node=4): 0x01, 0x0d, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x31, 0x04, 0x25, 0x21, 0xbf
2014-12-18 17:57:08.415 Node004, Queuing (Send) ThermostatModeCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x40, 0x02, 0x25, 0x22, 0xa6
2014-12-18 17:57:08.415 Node004, Queuing (Send) MultiChannel Encapsulated (instance=2): ThermostatModeCmd_Get (Node=4): 0x01, 0x0d, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x40, 0x02, 0x25, 0x23, 0xca
2014-12-18 17:57:08.415 Node004, Queuing (Send) AlarmCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x71, 0x04, 0x25, 0x24, 0x97
2014-12-18 17:57:08.416 Node004, Queuing (Query) Query Stage Complete (Dynamic)
2014-12-18 17:57:08.416 
2014-12-18 17:57:08.416 Node004, Sending (Send) message (Callback ID=0x1e, Expected Reply=0x04) - BasicCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x20, 0x02, 0x25, 0x1e, 0xfa
2014-12-18 17:57:08.421 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:08.421 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:08.437 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x1e, 0x00, 0xf7
2014-12-18 17:57:08.437 Node004,   ZW_SEND_DATA Request with callback ID 0x1e received (expected 0x1e)
2014-12-18 17:57:08.437 Node004, Request RTT 21 Average Request RTT 21
2014-12-18 17:57:08.447 Node004,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x04, 0x03, 0x20, 0x03, 0x01, 0xd7
2014-12-18 17:57:08.447 
2014-12-18 17:57:08.447 Node004, Response RTT 31 Average Response RTT 32
2014-12-18 17:57:08.447 Node004, Received Basic report from node 4: level=1
2014-12-18 17:57:08.447 Node004,   Expected reply and command class was received
2014-12-18 17:57:08.448 Node004,   Message transaction complete
2014-12-18 17:57:08.448 
2014-12-18 17:57:08.448 Node004, Removing current message
2014-12-18 17:57:08.448 
2014-12-18 17:57:08.448 Node004, Sending (Send) message (Callback ID=0x1f, Expected Reply=0x04) - MultiChannel Encapsulated (instance=2): BasicCmd_Get (Node=4): 0x01, 0x0d, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x20, 0x02, 0x25, 0x1f, 0x96
2014-12-18 17:57:08.454 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:08.454 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:08.471 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x1f, 0x00, 0xf6
2014-12-18 17:57:08.471 Node004,   ZW_SEND_DATA Request with callback ID 0x1f received (expected 0x1f)
2014-12-18 17:57:08.471 Node004, Request RTT 23 Average Request RTT 22
2014-12-18 17:57:08.483 Node004,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x04, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x20, 0x03, 0x01, 0xba
2014-12-18 17:57:08.483 
2014-12-18 17:57:08.483 Node004, Response RTT 35 Average Response RTT 33
2014-12-18 17:57:08.483 Node004, Received a MultiChannelEncap from node 4, endpoint 1 for Command Class COMMAND_CLASS_BASIC
2014-12-18 17:57:08.483 Node004, Received Basic report from node 4: level=1
2014-12-18 17:57:08.484 Node004,   Expected reply and command class was received
2014-12-18 17:57:08.484 Node004,   Message transaction complete
2014-12-18 17:57:08.484 
2014-12-18 17:57:08.484 Node004, Removing current message
2014-12-18 17:57:08.484 
2014-12-18 17:57:08.484 Node004, Sending (Send) message (Callback ID=0x20, Expected Reply=0x04) - SensorMultilevelCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x25, 0x20, 0xd3
2014-12-18 17:57:08.490 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:08.490 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:08.506 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x20, 0x00, 0xc9
2014-12-18 17:57:08.506 Node004,   ZW_SEND_DATA Request with callback ID 0x20 received (expected 0x20)
2014-12-18 17:57:08.506 Node004, Request RTT 22 Average Request RTT 22
2014-12-18 17:57:08.517 Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xfc, 0x76
2014-12-18 17:57:08.517 
2014-12-18 17:57:08.517 Node004, Response RTT 33 Average Response RTT 33
2014-12-18 17:57:08.517 Node004, Received SensorMultiLevel report from node 4, instance 1, Temperature: value=23.00C
2014-12-18 17:57:08.517 Node004, Initial read of value
2014-12-18 17:57:08.518 Node004,   Expected reply and command class was received
2014-12-18 17:57:08.518 Node004,   Message transaction complete
2014-12-18 17:57:08.518 
2014-12-18 17:57:08.518 Node004, Removing current message
2014-12-18 17:57:08.518 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 1 Index 1 Type decimal
2014-12-18 17:57:08.533 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 17:57:08.533 
2014-12-18 17:57:08.533 Node004, Sending (Send) message (Callback ID=0x21, Expected Reply=0x04) - MultiChannel Encapsulated (instance=2): SensorMultilevelCmd_Get (Node=4): 0x01, 0x0d, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x31, 0x04, 0x25, 0x21, 0xbf
2014-12-18 17:57:08.539 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:08.539 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:08.556 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x21, 0x00, 0xc8
2014-12-18 17:57:08.556 Node004,   ZW_SEND_DATA Request with callback ID 0x21 received (expected 0x21)
2014-12-18 17:57:08.556 Node004, Request RTT 22 Average Request RTT 22
2014-12-18 17:57:08.569 Node004,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x60, 0x0d, 0x01, 0x01, 0x31, 0x05, 0x01, 0x42, 0x08, 0xfc, 0x0b
2014-12-18 17:57:08.569 
2014-12-18 17:57:08.569 Node004, Response RTT 35 Average Response RTT 34
2014-12-18 17:57:08.569 Node004, Received a MultiChannelEncap from node 4, endpoint 1 for Command Class COMMAND_CLASS_SENSOR_MULTILEVEL
2014-12-18 17:57:08.569 Node004, Received SensorMultiLevel report from node 4, instance 2, Temperature: value=23.00C
2014-12-18 17:57:08.570 Node004, Initial read of value
2014-12-18 17:57:08.570 Node004,   Expected reply and command class was received
2014-12-18 17:57:08.570 Node004,   Message transaction complete
2014-12-18 17:57:08.570 
2014-12-18 17:57:08.570 Node004, Removing current message
2014-12-18 17:57:08.570 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 2 Index 1 Type decimal
2014-12-18 17:57:08.585 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 17:57:08.585 
2014-12-18 17:57:08.585 Node004, Sending (Send) message (Callback ID=0x22, Expected Reply=0x04) - ThermostatModeCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x40, 0x02, 0x25, 0x22, 0xa6
2014-12-18 17:57:08.591 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:08.591 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:08.607 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x22, 0x00, 0xcb
2014-12-18 17:57:08.607 Node004,   ZW_SEND_DATA Request with callback ID 0x22 received (expected 0x22)
2014-12-18 17:57:08.607 Node004, Request RTT 21 Average Request RTT 21
2014-12-18 17:57:08.617 Node004,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x04, 0x03, 0x40, 0x03, 0x01, 0xb7
2014-12-18 17:57:08.617 
2014-12-18 17:57:08.617 Node004, Response RTT 31 Average Response RTT 32
2014-12-18 17:57:08.617 Node004, Initial read of value
2014-12-18 17:57:08.617 Node004, Received thermostat mode: Heat
2014-12-18 17:57:08.618 Node004,   Expected reply and command class was received
2014-12-18 17:57:08.618 Node004,   Message transaction complete
2014-12-18 17:57:08.618 
2014-12-18 17:57:08.618 Node004, Removing current message
2014-12-18 17:57:08.618 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class THERMOSTAT MODE Instance 1 Index 0 Type list
2014-12-18 17:57:08.633 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 17:57:08.633 
2014-12-18 17:57:08.633 Node004, Sending (Send) message (Callback ID=0x23, Expected Reply=0x04) - MultiChannel Encapsulated (instance=2): ThermostatModeCmd_Get (Node=4): 0x01, 0x0d, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x40, 0x02, 0x25, 0x23, 0xca
2014-12-18 17:57:08.639 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:08.639 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:08.656 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x23, 0x00, 0xca
2014-12-18 17:57:08.656 Node004,   ZW_SEND_DATA Request with callback ID 0x23 received (expected 0x23)
2014-12-18 17:57:08.656 Node004, Request RTT 22 Average Request RTT 21
2014-12-18 17:57:08.668 Node004,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x04, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x40, 0x03, 0x01, 0xda
2014-12-18 17:57:08.668 
2014-12-18 17:57:08.668 Node004, Response RTT 34 Average Response RTT 33
2014-12-18 17:57:08.668 Node004, Received a MultiChannelEncap from node 4, endpoint 1 for Command Class COMMAND_CLASS_THERMOSTAT_MODE
2014-12-18 17:57:08.669 Node004, Initial read of value
2014-12-18 17:57:08.669 Node004, Received thermostat mode: Heat
2014-12-18 17:57:08.669 Node004,   Expected reply and command class was received
2014-12-18 17:57:08.669 Node004,   Message transaction complete
2014-12-18 17:57:08.669 
2014-12-18 17:57:08.669 Node004, Removing current message
2014-12-18 17:57:08.669 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class THERMOSTAT MODE Instance 2 Index 0 Type list
2014-12-18 17:57:08.684 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 17:57:08.685 
2014-12-18 17:57:08.685 Node004, Sending (Send) message (Callback ID=0x24, Expected Reply=0x04) - AlarmCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x71, 0x04, 0x25, 0x24, 0x97
2014-12-18 17:57:08.690 Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2014-12-18 17:57:08.690 Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2014-12-18 17:57:08.706 Node004,   Received: 0x01, 0x05, 0x00, 0x13, 0x24, 0x00, 0xcd
2014-12-18 17:57:08.706 Node004,   ZW_SEND_DATA Request with callback ID 0x24 received (expected 0x24)
2014-12-18 17:57:08.706 Node004, Request RTT 21 Average Request RTT 21
2014-12-18 17:57:08.719 Node004,   Received: 0x01, 0x07, 0x00, 0x04, 0x00, 0x04, 0x01, 0x71, 0x88
2014-12-18 17:57:08.719 
2014-12-18 17:57:08.719 Node004, Response RTT 34 Average Response RTT 33
2014-12-18 17:57:08.719 Node004,   Expected reply and command class was received
2014-12-18 17:57:08.719 Node004,   Message transaction complete
2014-12-18 17:57:08.720 
2014-12-18 17:57:08.720 Node004, Removing current message

2014-12-18 17:57:08.747 Node004, Query Stage Complete (Dynamic)
2014-12-18 17:57:08.747 Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2014-12-18 17:57:08.747 Node004, QueryStage_Configuration
2014-12-18 17:57:08.747 Node004, QueryStage_Complete
2014-12-18 17:57:08.747 CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2014-12-18 17:57:08.747 CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
2014-12-18 17:57:08.747          Node query processing complete.
2014-12-18 17:57:08.748 Notification: Node 4 Queries Complete
2014-12-18 17:57:08.859 Notification: All Nodes Queried
2014-12-18 17:58:00.542 SynoZwave Log : Find scene to launch and refresh virtual devices
2014-12-18 17:59:00.036 SynoZwave Log : Find scene to launch and refresh virtual devices
2014-12-18 17:59:26.400 Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0x91, 0x1b
2014-12-18 17:59:26.401 
2014-12-18 17:59:26.401 Node004, Received SensorMultiLevel report from node 4, instance 1, Temperature: value=21.93C
2014-12-18 17:59:26.401 Node004, Refreshed Value: old value=23.00, new value=21.93, type=string
2014-12-18 17:59:26.401 Node004, Changes to this value are not verified
2014-12-18 17:59:26.401 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 1 Index 1 Type decimal
2014-12-18 17:59:26.401 Notification: Add Graphics for Node 4 Value Id 2 Value 21.93
2014-12-18 17:59:26.504 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 17:59:26.504 Node004,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x60, 0x0d, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0x91, 0x66
2014-12-18 17:59:26.504 
2014-12-18 17:59:26.504 Node004, Cannot find endpoint map to instance for Command Class COMMAND_CLASS_SENSOR_MULTILEVEL endpoint 2

2014-12-18 18:01:09.056 Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0x5f, 0xd5
2014-12-18 18:01:09.057 
2014-12-18 18:01:09.057 Node004, Received SensorMultiLevel report from node 4, instance 1, Temperature: value=21.43C
2014-12-18 18:01:09.057 Node004, Refreshed Value: old value=21.93, new value=21.43, type=string
2014-12-18 18:01:09.057 Node004, Changes to this value are not verified
2014-12-18 18:01:09.057 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 1 Index 1 Type decimal
2014-12-18 18:01:09.057 Notification: Add Graphics for Node 4 Value Id 2 Value 21.43
2014-12-18 18:01:09.159 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 18:01:09.160 Node004,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x60, 0x0d, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0x5f, 0xa8
2014-12-18 18:01:09.160 
2014-12-18 18:01:09.160 Node004, Cannot find endpoint map to instance for Command Class COMMAND_CLASS_SENSOR_MULTILEVEL endpoint 2

2014-12-18 18:03:57.332 Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0x27, 0xad
2014-12-18 18:03:57.333 
2014-12-18 18:03:57.333 Node004, Received SensorMultiLevel report from node 4, instance 1, Temperature: value=20.87C
2014-12-18 18:03:57.333 Node004, Refreshed Value: old value=21.43, new value=20.87, type=string
2014-12-18 18:03:57.333 Node004, Changes to this value are not verified
2014-12-18 18:03:57.333 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 1 Index 1 Type decimal
2014-12-18 18:03:57.333 Notification: Add Graphics for Node 4 Value Id 2 Value 20.87
2014-12-18 18:03:57.438 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 18:03:57.439 Node004,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x60, 0x0d, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0x27, 0xd0
2014-12-18 18:03:57.439 
2014-12-18 18:03:57.439 Node004, Cannot find endpoint map to instance for Command Class COMMAND_CLASS_SENSOR_MULTILEVEL endpoint 2

2014-12-18 18:06:09.048 Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0x0e, 0x84
2014-12-18 18:06:09.048 
2014-12-18 18:06:09.048 Node004, Received SensorMultiLevel report from node 4, instance 1, Temperature: value=20.62C
2014-12-18 18:06:09.048 Node004, Refreshed Value: old value=20.87, new value=20.62, type=string
2014-12-18 18:06:09.048 Node004, Changes to this value are not verified
2014-12-18 18:06:09.048 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 1 Index 1 Type decimal
2014-12-18 18:06:09.048 Notification: Add Graphics for Node 4 Value Id 2 Value 20.62
2014-12-18 18:06:09.150 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 18:06:09.151 Node004,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x60, 0x0d, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0x0e, 0xf9
2014-12-18 18:06:09.151 
2014-12-18 18:06:09.151 Node004, Cannot find endpoint map to instance for Command Class COMMAND_CLASS_SENSOR_MULTILEVEL endpoint 2

2014-12-18 18:11:09.039 Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0x34, 0xbe
2014-12-18 18:11:09.039 
2014-12-18 18:11:09.039 Node004, Received SensorMultiLevel report from node 4, instance 1, Temperature: value=21.00C
2014-12-18 18:11:09.039 Node004, Refreshed Value: old value=20.62, new value=21.00, type=string
2014-12-18 18:11:09.039 Node004, Changes to this value are not verified
2014-12-18 18:11:09.039 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 1 Index 1 Type decimal
2014-12-18 18:11:09.039 Notification: Add Graphics for Node 4 Value Id 2 Value 21.00
2014-12-18 18:11:09.143 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-18 18:11:09.143 Node004,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x60, 0x0d, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0x34, 0xc3
2014-12-18 18:11:09.143 
2014-12-18 18:11:09.144 Node004, Cannot find endpoint map to instance for Command Class COMMAND_CLASS_SENSOR_MULTILEVEL endpoint 2
--------------------------------------------------------------
2014-12-19 11:56:07.143 Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0x8b, 0x01
2014-12-19 11:56:07.144 
2014-12-19 11:56:07.144 Node004, Received SensorMultiLevel report from node 4, instance 1, Temperature: value=21.87C
2014-12-19 11:56:07.144 Node004, Refreshed Value: old value=21.81, new value=21.87, type=string
2014-12-19 11:56:07.144 Node004, Changes to this value are not verified
2014-12-19 11:56:07.144 Notification: Value Changed Home 0x0184d9e3 Node 4 Genre user Class SENSOR MULTILEVEL Instance 1 Index 1 Type decimal
2014-12-19 11:56:07.144 Notification: Add Graphics for Node 4 Value Id 2 Value 21.87
2014-12-19 11:56:07.246 mgr,     Manager::WriteConfig completed for driver with home ID of 0x0184d9e3
2014-12-19 11:56:07.247 Node004,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x60, 0x0d, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0x8b, 0x7c
2014-12-19 11:56:07.247 
2014-12-19 11:56:07.247 Node004, Cannot find endpoint map to instance for Command Class COMMAND_CLASS_SENSOR_MULTILEVEL endpoint 2
2014-12-19 11:57:00.995 SynoZwave Log : Find scene to launch and refresh virtual devices
Posted by aleksey_z at Dec 19, 2014 11:05

What commands are available?

There is a How To page for Zwave. There you find all available commands

Posted by pz1 at Dec 19, 2014 11:29

Thank you, I've seen.
But I can not understand how to implement:
1. Power on / off thermostat.
2. The temperature sensor.
3. Change and display the set temperature.
As in the example: Stella Z with RazBerry.

If the switch is available commands on / off, then I saw the thermostat - heating / off. How to turn it on or off?

Posted by aleksey_z at Dec 19, 2014 13:20

In StellaZ with RaZberry thesituation is different. There I switch e.g. from Heating mode -> HeatSaving mode.
On the Raspberry webinterface I have preset the desired temperatures for each individual thermostat.
In one room (8*8 meter) I have experimentally set the four radiator thermosts thermostat to diffrent levels to get an even temperature distribution.
Afaik, that is not possible at present with OR.
As I do not use it any more, I have to leave answering the OR Zwave questions to others.

Posted by pz1 at Dec 19, 2014 13:39

Got it, I'll try to understand, and wait for the update OR.
Thank you.

Posted by aleksey_z at Dec 19, 2014 14:17

I connect the thermostat, but only as a slider for setting a heating temperature and for displaying this value.


I would like to get the data from the sensor temperature of the thermostat, and the ability to change the mode of operation of the thermostat (Off mode and Heat mode).

Prompt, getting it done?

Posted by aleksey_z at Dec 20, 2014 15:34

Up.
Please help.

Posted by aleksey_z at Dec 21, 2014 18:13

Why can not I get information about the operating mode of the thermostat (Heat / Off),
can not get the data from the temperature sensor (sensor on the thermostat), I can not On / Off thermostat?
All of these features work in SynoZWave. And in the logs SynoZWave seen that all the data is sent.
Why can not I get these features in OpenRemote?

Posted by aleksey_z at Dec 22, 2014 14:10

@Rainer

The new Z-Wave implementation (free version will be released very soon)

Will the free version still be restricted to 10 devices?

Posted by pz1 at Dec 23, 2014 14:47

The free version will be unrestricted that means unlimited number of devices.

Posted by rhitz at Dec 24, 2014 13:47

In the logs every 5 minutes sent some data. Perhaps this is the temperature value from the temperature sensor (in the thermostat).
If so, how these data derive?

Posted by aleksey_z at Dec 28, 2014 13:58

I do not see the new version. Delayed output?

Posted by aleksey_z at Jan 06, 2015 13:40

Updated. In zwave.logs:

--------------------------------------------------------------------

  OpenRemote Z-Wave protocol version : '2.0.0'

--------------------------------------------------------------------

INFO 2015-01-21 12:47:16,046 (Z-Wave):  Serial_Port                 : Opening the serial port [COM port: /dev/ttyUSB0, Communication Layer: RXTX]...
INFO 2015-01-21 12:47:16,215 (Z-Wave):  Serial_Port                 : The serial port [COM port: /dev/ttyUSB0, Communication Layer: RXTX] has been successfully opened.
INFO 2015-01-21 12:47:16,369 (Z-Wave):  Z-Wave firmware version     : Z-Wave 2.78
INFO 2015-01-21 12:47:16,370 (Z-Wave):  Z-Wave library type         : ZW_LIB_CONTROLLER_STATIC
INFO 2015-01-21 12:47:16,418 (Z-Wave):  Serial API Z-Wave firmware  : [Version=3.7, Manufacturer='AEON Labs', Product Type=0x0002, Product ID=0x0001]
INFO 2015-01-21 12:47:16,424 (Z-Wave):  Serial API Z-Wave functions : [0x02 ,0x03 ,0x04 ,0x05 ,0x06 ,0x07 ,0x08 ,0x10 ,0x12 ,0x13 ,0x14 ,0x15 ,0x16 ,0x17 ,0x18 ,0x1C ,0x20 ,0x21 ,0x22 ,0x23 ,0x24 ,0x41 ,0x42 ,0x44 ,0x45 ,0x46 ,0x47 ,0x48 ,0x49 ,0x4A ,0x4B ,0x4D ,0x50 ,0x51 ,0x52 ,0x53 ,0x54 ,0x55 ,0x56 ,0x57 ,0x5A ,0x60 ,0x61 ,0x62 ,0x63 ,0x80 ,0x90 ,]
INFO 2015-01-21 12:47:16,574 (Z-Wave):  Controller                  : Z-Wave node info (ID=1) : [[Device Classes : 'BASIC_TYPE_STATIC_CONTROLLER','GENERIC_TYPE_STATIC_CONTROLLER','SPECIFIC_TYPE_PC_CONTROLLER'],[Capabilities : 'NODEINFO_CAPABILITY_LISTENING'],[Security : '--']].
INFO 2015-01-21 12:47:16,722 (Z-Wave):  Controller                  : Z-Wave node info (ID=2) : [[Device Classes : 'BASIC_TYPE_ROUTING_SLAVE','GENERIC_TYPE_SWITCH_MULTILEVEL','SPECIFIC_TYPE_POWER_SWITCH_MULTILEVEL'],[Capabilities : 'NODEINFO_CAPABILITY_ROUTING','NODEINFO_CAPABILITY_LISTENING'],[Security : 'NODEINFO_SECURITY_OPTIONAL_FUNC_SUPPORT']].
INFO 2015-01-21 12:47:16,787 (Z-Wave):  Controller                  : Z-Wave node info (ID=3) : [[Device Classes : 'BASIC_TYPE_ROUTING_SLAVE','GENERIC_TYPE_SWITCH_BINARY','SPECIFIC_TYPE_POWER_SWITCH_BINARY'],[Capabilities : 'NODEINFO_CAPABILITY_ROUTING','NODEINFO_CAPABILITY_LISTENING'],[Security : 'NODEINFO_SECURITY_OPTIONAL_FUNC_SUPPORT']].
INFO 2015-01-21 12:47:16,868 (Z-Wave):  Controller                  : Z-Wave node info (ID=4) : [[Device Classes : 'BASIC_TYPE_ROUTING_SLAVE','GENERIC_TYPE_THERMOSTAT','SPECIFIC_TYPE_THERMOSTAT_GENERAL_V2'],[Capabilities : 'NODEINFO_CAPABILITY_ROUTING','NODEINFO_CAPABILITY_LISTENING'],[Security : 'NODEINFO_SECURITY_OPTIONAL_FUNC_SUPPORT']].
INFO 2015-01-21 12:47:17,230 (Z-Wave): Node '2' : Node [Manufacturer='Fibargroup', Type='Universal Dimmer 500W FGD-211'] has been successfully initialized.
INFO 2015-01-21 12:47:17,900 (Z-Wave): Node '3' : Node [Manufacturer='Fibargroup', Type='Double Relay Switch FGS-221'] has been successfully initialized.
INFO 2015-01-21 12:47:18,032 (Z-Wave): Node '4' : Node [Manufacturer='ConnectHome', Type='Unknown'] has been successfully initialized.
DEBUG 2015-01-21 12:47:17,318 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@c85c0f'.
DEBUG 2015-01-21 12:47:17,319 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@c85c0f'...
DEBUG 2015-01-21 12:47:17,308 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,321 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0A)' request (callback ID=0x0A).
DEBUG 2015-01-21 12:47:17,321 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x00, 0x04, 0x00, 0x04, 0x03, 0x20, 0x03, 0x00, 0xD6]
DEBUG 2015-01-21 12:47:17,324 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x25, 0x02, 0x05, 0x0A, 0xA3].
DEBUG 2015-01-21 12:47:17,327 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x00, 0x04, 0x00, 0x04, 0x03, 0x20, 0x03, 0x00, 0xD6] has been received.
DEBUG 2015-01-21 12:47:17,329 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_BASIC', Command='0x03', Parameters: [0x00]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0A)'.
DEBUG 2015-01-21 12:47:17,335 (Z-Wave): Node_Initializer            : Node '4:0' : [COMMAND_CLASS_BASIC::BASIC_REPORT (Retry='1'), Value='0']
DEBUG 2015-01-21 12:47:17,336 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_SENSOR_MULTILEVEL]
DEBUG 2015-01-21 12:47:17,337 (Z-Wave): Node_Initializer            : Node '4:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')]
DEBUG 2015-01-21 12:47:17,340 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x09)
DEBUG 2015-01-21 12:47:17,346 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-21 12:47:17,348 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x18]
DEBUG 2015-01-21 12:47:17,351 (Z-Wave): RX_Frame_Layer              : CAN -> Failed to send data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x25, 0x02, 0x05, 0x0A, 0xA3] because the Z-Wave firmware has an ACK expected instead of the sent frame
DEBUG 2015-01-21 12:47:17,351 (Z-Wave): TX_Frame_Layer              : CAN -> Wait 100 [ms] before next retransmission attempt.
DEBUG 2015-01-21 12:47:17,384 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-21 12:47:17,385 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '2', endPoint = '1'
DEBUG 2015-01-21 12:47:17,385 (Z-Wave): Command_Builder             : Z-Wave command: command = status
DEBUG 2015-01-21 12:47:17,386 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-21 12:47:17,386 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-21 12:47:17,457 (Z-Wave): TX_Frame_Layer              : Retransmission attempt 1, data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x25, 0x02, 0x05, 0x0A, 0xA3]
DEBUG 2015-01-21 12:47:17,473 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-21 12:47:17,474 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-21 12:47:17,475 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '3', endPoint = '1'
DEBUG 2015-01-21 12:47:17,475 (Z-Wave): Command_Builder             : Z-Wave command: command = status
DEBUG 2015-01-21 12:47:17,476 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-21 12:47:17,476 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x25, 0x02, 0x05, 0x0A, 0xA3] transmission OK.
DEBUG 2015-01-21 12:47:17,476 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-21 12:47:17,477 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0A)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-21 12:47:17,477 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,479 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-21 12:47:17,480 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-21 12:47:17,482 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0A).
DEBUG 2015-01-21 12:47:17,482 (Z-Wave): RX_Session_Layer            : Waiting 64,838 [ms] for 'ZW_SendData' callback frame (callback ID=0x0A).
DEBUG 2015-01-21 12:47:17,489 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,490 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0A, 0x00, 0xE3]
DEBUG 2015-01-21 12:47:17,492 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0A, 0x00, 0xE3] has been received.
DEBUG 2015-01-21 12:47:17,494 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0A, 0x00, 0xE3] (callback ID=0x0A) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0A)' successfully executed.
DEBUG 2015-01-21 12:47:17,496 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@c85c0f
DEBUG 2015-01-21 12:47:17,497 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-21 12:47:17,498 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,498 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-21 12:47:17,500 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)' request (callback ID=0x0B).
DEBUG 2015-01-21 12:47:17,501 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0D, 0x00, 0x04, 0x00, 0x03, 0x07, 0x60, 0x0D, 0x02, 0x00, 0x25, 0x03, 0x00, 0xBB]
DEBUG 2015-01-21 12:47:17,502 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x05, 0x0B, 0xD8].
DEBUG 2015-01-21 12:47:17,505 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0D, 0x00, 0x04, 0x00, 0x03, 0x07, 0x60, 0x0D, 0x02, 0x00, 0x25, 0x03, 0x00, 0xBB] has been received.
DEBUG 2015-01-21 12:47:17,508 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='3', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x02, 0x00, 0x25, 0x03, 0x00]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)'.
DEBUG 2015-01-21 12:47:17,510 (Z-Wave): Node_Initializer            : Node '3:2' : [COMMAND_CLASS_SWITCH_BINARY::SWITCH_BINARY_REPORT (Retry='1'), State='OFF']
DEBUG 2015-01-21 12:47:17,511 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_SWITCH_BINARY]
DEBUG 2015-01-21 12:47:17,511 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@c85c0f
DEBUG 2015-01-21 12:47:17,512 (Z-Wave): Node_Initializer            : Node '3:1' : [COMMAND_CLASS_SWITCH_BINARY::SWITCH_BINARY_GET (Retry='1')]
DEBUG 2015-01-21 12:47:17,516 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@677ce0'.
DEBUG 2015-01-21 12:47:17,525 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x18]
DEBUG 2015-01-21 12:47:17,527 (Z-Wave): RX_Frame_Layer              : CAN -> Failed to send data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x05, 0x0B, 0xD8] because the Z-Wave firmware has an ACK expected instead of the sent frame
DEBUG 2015-01-21 12:47:17,528 (Z-Wave): TX_Frame_Layer              : CAN -> Wait 100 [ms] before next retransmission attempt.
DEBUG 2015-01-21 12:47:17,633 (Z-Wave): TX_Frame_Layer              : Retransmission attempt 1, data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x05, 0x0B, 0xD8]
DEBUG 2015-01-21 12:47:17,644 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-21 12:47:17,646 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x05, 0x0B, 0xD8] transmission OK.
DEBUG 2015-01-21 12:47:17,648 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-21 12:47:17,648 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,650 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-21 12:47:17,651 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-21 12:47:17,653 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0B).
DEBUG 2015-01-21 12:47:17,653 (Z-Wave): RX_Session_Layer            : Waiting 64,846 [ms] for 'ZW_SendData' callback frame (callback ID=0x0B).
DEBUG 2015-01-21 12:47:17,664 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,666 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0B, 0x00, 0xE2]
DEBUG 2015-01-21 12:47:17,666 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-21 12:47:17,667 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '4', endPoint = '1'
DEBUG 2015-01-21 12:47:17,667 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0B, 0x00, 0xE2] has been received.
DEBUG 2015-01-21 12:47:17,667 (Z-Wave): Command_Builder             : Z-Wave command: command = temp
DEBUG 2015-01-21 12:47:17,668 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-21 12:47:17,668 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-21 12:47:17,670 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0B, 0x00, 0xE2] (callback ID=0x0B) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)' successfully executed.
DEBUG 2015-01-21 12:47:17,672 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)
DEBUG 2015-01-21 12:47:17,673 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@677ce0'.
DEBUG 2015-01-21 12:47:17,673 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@677ce0'...
DEBUG 2015-01-21 12:47:17,675 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0C)' request (callback ID=0x0C).
DEBUG 2015-01-21 12:47:17,676 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,677 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x25, 0x02, 0x05, 0x0C, 0xA6].
DEBUG 2015-01-21 12:47:17,679 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0x7F, 0xF5]
DEBUG 2015-01-21 12:47:17,682 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0x7F, 0xF5] has been received.
DEBUG 2015-01-21 12:47:17,684 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_MULTILEVEL', Command='0x05', Parameters: [0x01, 0x42, 0x08, 0x7F]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0C)'.
DEBUG 2015-01-21 12:47:17,692 (Z-Wave): Node_Initializer            : Node '4:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT (Retry='1'), Value='21.75', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-21 12:47:17,693 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_MANUFACTURER_SPECIFIC]
DEBUG 2015-01-21 12:47:17,696 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x18]
DEBUG 2015-01-21 12:47:17,707 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_ASSOCIATION]
DEBUG 2015-01-21 12:47:17,708 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_VERSION]
DEBUG 2015-01-21 12:47:17,709 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION_V2]
DEBUG 2015-01-21 12:47:17,709 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_SENSOR_MULTILEVEL]
DEBUG 2015-01-21 12:47:17,710 (Z-Wave): Node_Initializer            : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')]
DEBUG 2015-01-21 12:47:17,711 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@19e8a43'.
DEBUG 2015-01-21 12:47:17,712 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)
DEBUG 2015-01-21 12:47:17,715 (Z-Wave): RX_Frame_Layer              : CAN -> Failed to send data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x25, 0x02, 0x05, 0x0C, 0xA6] because the Z-Wave firmware has an ACK expected instead of the sent frame
DEBUG 2015-01-21 12:47:17,716 (Z-Wave): TX_Frame_Layer              : CAN -> Wait 100 [ms] before next retransmission attempt.
DEBUG 2015-01-21 12:47:17,727 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-21 12:47:17,728 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '4', endPoint = '1'
DEBUG 2015-01-21 12:47:17,729 (Z-Wave): Command_Builder             : Z-Wave command: command = status
DEBUG 2015-01-21 12:47:17,729 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-21 12:47:17,729 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-21 12:47:17,825 (Z-Wave): TX_Frame_Layer              : Retransmission attempt 1, data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x25, 0x02, 0x05, 0x0C, 0xA6]
DEBUG 2015-01-21 12:47:17,845 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-21 12:47:17,846 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-21 12:47:17,848 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x25, 0x02, 0x05, 0x0C, 0xA6] transmission OK.
DEBUG 2015-01-21 12:47:17,850 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0C)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-21 12:47:17,853 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-21 12:47:17,855 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0C).
DEBUG 2015-01-21 12:47:17,856 (Z-Wave): RX_Session_Layer            : Waiting 64,819 [ms] for 'ZW_SendData' callback frame (callback ID=0x0C).
DEBUG 2015-01-21 12:47:17,858 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,860 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0C, 0x00, 0xE5]
DEBUG 2015-01-21 12:47:17,864 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0C, 0x00, 0xE5] has been received.
DEBUG 2015-01-21 12:47:17,867 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0C, 0x00, 0xE5] (callback ID=0x0C) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0C)' successfully executed.
DEBUG 2015-01-21 12:47:17,867 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,870 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0D, 0x00, 0x04, 0x00, 0x03, 0x07, 0x60, 0x0D, 0x01, 0x00, 0x25, 0x03, 0x00, 0xB8]
DEBUG 2015-01-21 12:47:17,879 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@677ce0
DEBUG 2015-01-21 12:47:17,880 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@19e8a43'.
DEBUG 2015-01-21 12:47:17,881 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@19e8a43'...
DEBUG 2015-01-21 12:47:17,882 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0D, 0x00, 0x04, 0x00, 0x03, 0x07, 0x60, 0x0D, 0x01, 0x00, 0x25, 0x03, 0x00, 0xB8] has been received.
DEBUG 2015-01-21 12:47:17,882 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)' request (callback ID=0x0D).
DEBUG 2015-01-21 12:47:17,886 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x31, 0x04, 0x05, 0x0D, 0xB1].
DEBUG 2015-01-21 12:47:17,889 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='3', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x01, 0x00, 0x25, 0x03, 0x00]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)'.
DEBUG 2015-01-21 12:47:17,893 (Z-Wave): Node_Initializer            : Node '3:1' : [COMMAND_CLASS_SWITCH_BINARY::SWITCH_BINARY_REPORT (Retry='1'), State='OFF']
DEBUG 2015-01-21 12:47:17,895 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@677ce0
INFO 2015-01-21 12:47:17,900 (Z-Wave): Node '3' : Node [Manufacturer='Fibargroup', Type='Double Relay Switch FGS-221'] has been successfully initialized.
DEBUG 2015-01-21 12:47:17,904 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-21 12:47:17,908 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x31, 0x04, 0x05, 0x0D, 0xB1] transmission OK.
DEBUG 2015-01-21 12:47:17,908 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,909 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-21 12:47:17,909 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-21 12:47:17,913 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-21 12:47:17,914 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0D).
DEBUG 2015-01-21 12:47:17,923 (Z-Wave): RX_Session_Layer            : Waiting 64,966 [ms] for 'ZW_SendData' callback frame (callback ID=0x0D).
DEBUG 2015-01-21 12:47:17,924 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,926 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0D, 0x00, 0xE4]
DEBUG 2015-01-21 12:47:17,941 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0D, 0x00, 0xE4] has been received.
DEBUG 2015-01-21 12:47:17,942 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,944 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0D, 0x00, 0xE4] (callback ID=0x0D) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)' successfully executed.
DEBUG 2015-01-21 12:47:17,945 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@19e8a43
DEBUG 2015-01-21 12:47:17,946 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0x7F, 0x8A]
DEBUG 2015-01-21 12:47:17,951 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0x7F, 0x8A] has been received.
DEBUG 2015-01-21 12:47:17,964 (Z-Wave): Node_Initializer            : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT (Retry='1'), Value='21.75', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-21 12:47:17,964 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_SENSOR_MULTILEVEL]
DEBUG 2015-01-21 12:47:17,965 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@19e8a43
DEBUG 2015-01-21 12:47:17,966 (Z-Wave): Node_Initializer            : Node '4:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')]
DEBUG 2015-01-21 12:47:17,967 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@c40868'.
DEBUG 2015-01-21 12:47:17,967 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@c40868'.
DEBUG 2015-01-21 12:47:17,967 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@c40868'...
DEBUG 2015-01-21 12:47:17,969 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0E)' request (callback ID=0x0E).
DEBUG 2015-01-21 12:47:17,973 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x0E, 0xB1].
DEBUG 2015-01-21 12:47:17,989 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-21 12:47:17,993 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x0E, 0xB1] transmission OK.
DEBUG 2015-01-21 12:47:17,993 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:17,995 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-21 12:47:17,995 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0E)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-21 12:47:18,002 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-21 12:47:18,004 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0E).
DEBUG 2015-01-21 12:47:18,004 (Z-Wave): RX_Session_Layer            : Waiting 64,964 [ms] for 'ZW_SendData' callback frame (callback ID=0x0E).
DEBUG 2015-01-21 12:47:18,009 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:18,011 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0E, 0x00, 0xE7]
DEBUG 2015-01-21 12:47:18,013 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0E, 0x00, 0xE7] has been received.
DEBUG 2015-01-21 12:47:18,016 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0E, 0x00, 0xE7] (callback ID=0x0E) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0E)' successfully executed.
DEBUG 2015-01-21 12:47:18,017 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@c40868
DEBUG 2015-01-21 12:47:18,022 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 12:47:18,025 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0x7F, 0x89]
DEBUG 2015-01-21 12:47:18,030 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0x7F, 0x89] has been received.
DEBUG 2015-01-21 12:47:18,031 (Z-Wave): Node_Initializer            : Node '4:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT (Retry='1'), Value='21.75', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
INFO 2015-01-21 12:47:18,032 (Z-Wave): Node '4' : Node [Manufacturer='ConnectHome', Type='Unknown'] has been successfully initialized.
DEBUG 2015-01-21 12:47:18,034 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@c40868

Does not work now slider temperature control thermostat, the thermostat will not turn on.
In zwave.logs:

DEBUG 2015-01-21 14:41:34,581 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 14:41:34,582 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0x9E, 0x69]
DEBUG 2015-01-21 14:41:34,583 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0x9E, 0x69] has been received.
DEBUG 2015-01-21 14:41:34,584 (Z-Wave): RX_Session_Layer            : Node '4' : [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0x9E]]
DEBUG 2015-01-21 14:41:34,585 (Z-Wave): CCMultiLevelSensor          : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='22.06', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-21 14:43:45,724 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 14:43:45,725 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xB1, 0x3B]
DEBUG 2015-01-21 14:43:45,726 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xB1, 0x3B] has been received.
DEBUG 2015-01-21 14:43:45,727 (Z-Wave): RX_Session_Layer            : Node '4' : [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_MULTILEVEL', Command='0x05', Parameters: [0x01, 0x42, 0x08, 0xB1]]
DEBUG 2015-01-21 14:43:45,728 (Z-Wave): CCMultiLevelSensor          : Node '4:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='22.25', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-21 14:43:45,748 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-21 14:43:45,749 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xB1, 0x46]
DEBUG 2015-01-21 14:43:45,750 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xB1, 0x46] has been received.
DEBUG 2015-01-21 14:43:45,751 (Z-Wave): RX_Session_Layer            : Node '4' : [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xB1]]
DEBUG 2015-01-21 14:43:45,752 (Z-Wave): CCMultiLevelSensor          : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='22.25', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-21 14:44:22,936 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-21 14:44:22,936 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '4', endPoint = '1'
DEBUG 2015-01-21 14:44:22,936 (Z-Wave): Command_Builder             : Z-Wave command: command = temp
DEBUG 2015-01-21 14:44:22,937 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = 30
DEBUG 2015-01-21 14:44:22,937 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-21 14:44:28,181 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-21 14:44:28,182 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '4', endPoint = '1'
DEBUG 2015-01-21 14:44:28,182 (Z-Wave): Command_Builder             : Z-Wave command: command = temp
DEBUG 2015-01-21 14:44:28,182 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = 11
DEBUG 2015-01-21 14:44:28,182 (Z-Wave): Command_Builder             : Z-Wave command created successfully

Still appeared 3 temperature sensor, which one to use?
4:0
4:1
4:2
But the temperature data, I can not display the panel.

Posted by aleksey_z at Jan 22, 2015 09:20

Having dealt with the temperature sensor (in the thermostat). Was able to obtain the values of temperature sensors 4: 1 and 4: 2.
But with 4: 0 nothing. And he gives in the log values of temperature.
Created 3 commands and 3 sensor.
What does it mean?

Posted by aleksey_z at Jan 22, 2015 11:25

Try it with the following commands:

Z-Wave nodeID: 4
Command: AIR_TEMPERATURE_SCALE_CELSIUS

Z-Wave nodeID: 4:1
Command: AIR_TEMPERATURE_SCALE_CELSIUS

Z-Wave nodeID: 4:2
Command: AIR_TEMPERATURE_SCALE_CELSIUS

That means remove the zero from the root device command.

Do you have any information why this device has 3 different temperature sensors. I could imagine that one temperature is the setpoint value and the second one is the current temperature but the third one ?

I'm interested in the content of the node4.xml file and also the log statement that shows all supported and controlled command classes of this device - I'ld like to learn how these thermostat devices work.

Posted by rhitz at Jan 22, 2015 13:16

If so:
Z-Wave nodeID: 4
work and also shows the current temperature. (as a nodeID 4:1 and 4:2)

Himself thermostat: http://z-wave.com.ua/p47082561-termostat-wave-seti.html
Log after reboot OR:

DEBUG 2015-01-22 16:25:25,008 (Z-Wave): Connection_Manager          : Adding shutdown hook to manage unclosed Z-Wave connections in case of controller exit.
INFO 2015-01-22 16:25:25,109 (Z-Wave):  Serial_Port                 : Opening the serial port [COM port: /dev/ttyUSB0, Communication Layer: RXTX]...
INFO 2015-01-22 16:25:25,273 (Z-Wave):  Serial_Port                 : The serial port [COM port: /dev/ttyUSB0, Communication Layer: RXTX] has been successfully opened.
DEBUG 2015-01-22 16:25:25,314 (Z-Wave): Controller                  : Retrieving Z-Wave controller firmware version and library type...
DEBUG 2015-01-22 16:25:25,325 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_Version'.
DEBUG 2015-01-22 16:25:25,325 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_Version'.
DEBUG 2015-01-22 16:25:25,330 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_Version'...
DEBUG 2015-01-22 16:25:25,347 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x03, 0x00, 0x15, 0xE9].
DEBUG 2015-01-22 16:25:25,359 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,361 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x03, 0x00, 0x15, 0xE9] transmission OK.
DEBUG 2015-01-22 16:25:25,362 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_Version' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,364 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01, 0x10, 0x01, 0x15, 0x5A, 0x2D, 0x57, 0x61, 0x76, 0x65, 0x20, 0x32, 0x2E, 0x37, 0x38, 0x00, 0x01, 0x9B]
DEBUG 2015-01-22 16:25:25,372 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x01, 0x15, 0x5A, 0x2D, 0x57, 0x61, 0x76, 0x65, 0x20, 0x32, 0x2E, 0x37, 0x38, 0x00, 0x01, 0x9B] has been received.
DEBUG 2015-01-22 16:25:25,377 (Z-Wave): RX_Session_Layer            : Response received [0x01, 0x10, 0x01, 0x15, 0x5A, 0x2D, 0x57, 0x61, 0x76, 0x65, 0x20, 0x32, 0x2E, 0x37, 0x38, 0x00, 0x01, 0x9B] -> Z-Wave function 'ZW_Version' successfully executed.
INFO 2015-01-22 16:25:25,386 (Z-Wave):  Z-Wave firmware version     : Z-Wave 2.78
INFO 2015-01-22 16:25:25,387 (Z-Wave):  Z-Wave library type         : ZW_LIB_CONTROLLER_STATIC
DEBUG 2015-01-22 16:25:25,387 (Z-Wave): Controller                  : Retrieving serial API capabilities...
DEBUG 2015-01-22 16:25:25,392 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'SERIAL_API_GetCapabilities'.
DEBUG 2015-01-22 16:25:25,392 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'SERIAL_API_GetCapabilities'.
DEBUG 2015-01-22 16:25:25,393 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'SERIAL_API_GetCapabilities'...
DEBUG 2015-01-22 16:25:25,394 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x03, 0x00, 0x07, 0xFB].
DEBUG 2015-01-22 16:25:25,403 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,404 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x03, 0x00, 0x07, 0xFB] transmission OK.
DEBUG 2015-01-22 16:25:25,405 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'SERIAL_API_GetCapabilities' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,411 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01, 0x2B, 0x01, 0x07, 0x03, 0x07, 0x00, 0x86, 0x00, 0x02, 0x00, 0x01, 0xFE, 0x80, 0xFE, 0x88, 0x0F, 0x00, 0x00, 0x00, 0xFB, 0x97, 0x7F, 0x82, 0x07, 0x00, 0x00, 0x80, 0x00, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0xC2]
DEBUG 2015-01-22 16:25:25,421 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x2B, 0x01, 0x07, 0x03, 0x07, 0x00, 0x86, 0x00, 0x02, 0x00, 0x01, 0xFE, 0x80, 0xFE, 0x88, 0x0F, 0x00, 0x00, 0x00, 0xFB, 0x97, 0x7F, 0x82, 0x07, 0x00, 0x00, 0x80, 0x00, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0xC2] has been received.
DEBUG 2015-01-22 16:25:25,431 (Z-Wave): RX_Session_Layer            : Response received [0x01, 0x2B, 0x01, 0x07, 0x03, 0x07, 0x00, 0x86, 0x00, 0x02, 0x00, 0x01, 0xFE, 0x80, 0xFE, 0x88, 0x0F, 0x00, 0x00, 0x00, 0xFB, 0x97, 0x7F, 0x82, 0x07, 0x00, 0x00, 0x80, 0x00, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0xC2] -> Z-Wave function 'SERIAL_API_GetCapabilities' successfully executed.
INFO 2015-01-22 16:25:25,439 (Z-Wave):  Serial API Z-Wave firmware  : [Version=3.7, Manufacturer='AEON Labs', Product Type=0x0002, Product ID=0x0001]
INFO 2015-01-22 16:25:25,465 (Z-Wave):  Serial API Z-Wave functions : [0x02 ,0x03 ,0x04 ,0x05 ,0x06 ,0x07 ,0x08 ,0x10 ,0x12 ,0x13 ,0x14 ,0x15 ,0x16 ,0x17 ,0x18 ,0x1C ,0x20 ,0x21 ,0x22 ,0x23 ,0x24 ,0x41 ,0x42 ,0x44 ,0x45 ,0x46 ,0x47 ,0x48 ,0x49 ,0x4A ,0x4B ,0x4D ,0x50 ,0x51 ,0x52 ,0x53 ,0x54 ,0x55 ,0x56 ,0x57 ,0x5A ,0x60 ,0x61 ,0x62 ,0x63 ,0x80 ,0x90 ,]
DEBUG 2015-01-22 16:25:25,466 (Z-Wave): Controller                  : Retrieving Z-Wave node list...
DEBUG 2015-01-22 16:25:25,472 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'SERIAL_API_GetInitData'.
DEBUG 2015-01-22 16:25:25,472 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'SERIAL_API_GetInitData'.
DEBUG 2015-01-22 16:25:25,472 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'SERIAL_API_GetInitData'...
DEBUG 2015-01-22 16:25:25,474 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x03, 0x00, 0x02, 0xFE].
DEBUG 2015-01-22 16:25:25,479 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,480 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x03, 0x00, 0x02, 0xFE] transmission OK.
DEBUG 2015-01-22 16:25:25,481 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'SERIAL_API_GetInitData' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,526 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,532 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x25, 0x01, 0x02, 0x05, 0x08, 0x1D, 0x0F, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0xC4]
DEBUG 2015-01-22 16:25:25,539 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x25, 0x01, 0x02, 0x05, 0x08, 0x1D, 0x0F, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0xC4] has been received.
DEBUG 2015-01-22 16:25:25,545 (Z-Wave): RX_Session_Layer            : Response received [0x01, 0x25, 0x01, 0x02, 0x05, 0x08, 0x1D, 0x0F, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0xC4] -> Z-Wave function 'SERIAL_API_GetInitData' successfully executed.
DEBUG 2015-01-22 16:25:25,557 (Z-Wave): Controller                  : The Z-Wave node list has been successfully retrieved : [1, 2, 3, 4].
DEBUG 2015-01-22 16:25:25,578 (Z-Wave): Controller                  : Retrieving Z-Wave node info (ID=1)...
DEBUG 2015-01-22 16:25:25,584 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=1)'.
DEBUG 2015-01-22 16:25:25,584 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=1)'.
DEBUG 2015-01-22 16:25:25,585 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=1)'...
DEBUG 2015-01-22 16:25:25,586 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x04, 0x00, 0x41, 0x01, 0xBB].
DEBUG 2015-01-22 16:25:25,592 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,594 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x04, 0x00, 0x41, 0x01, 0xBB] transmission OK.
DEBUG 2015-01-22 16:25:25,595 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=1)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,596 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,598 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x01, 0x41, 0x92, 0x16, 0x00, 0x02, 0x02, 0x01, 0x33]
DEBUG 2015-01-22 16:25:25,601 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x01, 0x41, 0x92, 0x16, 0x00, 0x02, 0x02, 0x01, 0x33] has been received.
DEBUG 2015-01-22 16:25:25,604 (Z-Wave): RX_Session_Layer            : Response received [0x01, 0x09, 0x01, 0x41, 0x92, 0x16, 0x00, 0x02, 0x02, 0x01, 0x33] -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=1)' successfully executed.
INFO 2015-01-22 16:25:25,616 (Z-Wave):  Controller                  : Z-Wave node info (ID=1) : [[Device Classes : 'BASIC_TYPE_STATIC_CONTROLLER','GENERIC_TYPE_STATIC_CONTROLLER','SPECIFIC_TYPE_PC_CONTROLLER'],[Capabilities : 'NODEINFO_CAPABILITY_LISTENING'],[Security : '--']].
DEBUG 2015-01-22 16:25:25,617 (Z-Wave): Controller                  : Retrieving Z-Wave node info (ID=2)...
DEBUG 2015-01-22 16:25:25,618 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=2)'.
DEBUG 2015-01-22 16:25:25,618 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=2)'.
DEBUG 2015-01-22 16:25:25,618 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=2)'...
DEBUG 2015-01-22 16:25:25,620 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x04, 0x00, 0x41, 0x02, 0xB8].
DEBUG 2015-01-22 16:25:25,626 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,627 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,628 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x04, 0x00, 0x41, 0x02, 0xB8] transmission OK.
DEBUG 2015-01-22 16:25:25,628 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=2)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,629 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x01, 0x41, 0xD3, 0x9C, 0x00, 0x04, 0x11, 0x01, 0xED]
DEBUG 2015-01-22 16:25:25,632 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x01, 0x41, 0xD3, 0x9C, 0x00, 0x04, 0x11, 0x01, 0xED] has been received.
DEBUG 2015-01-22 16:25:25,634 (Z-Wave): RX_Session_Layer            : Response received [0x01, 0x09, 0x01, 0x41, 0xD3, 0x9C, 0x00, 0x04, 0x11, 0x01, 0xED] -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=2)' successfully executed.
DEBUG 2015-01-22 16:25:25,762 (Z-Wave): Node                        : Node '2' : Node has been successfully initialized with the following command classes...
INFO 2015-01-22 16:25:25,762 (Z-Wave):  Controller                  : Z-Wave node info (ID=2) : [[Device Classes : 'BASIC_TYPE_ROUTING_SLAVE','GENERIC_TYPE_SWITCH_MULTILEVEL','SPECIFIC_TYPE_POWER_SWITCH_MULTILEVEL'],[Capabilities : 'NODEINFO_CAPABILITY_ROUTING','NODEINFO_CAPABILITY_LISTENING'],[Security : 'NODEINFO_SECURITY_OPTIONAL_FUNC_SUPPORT']].
DEBUG 2015-01-22 16:25:25,763 (Z-Wave): Controller                  : Retrieving Z-Wave node info (ID=3)...
DEBUG 2015-01-22 16:25:25,764 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=3)'.
DEBUG 2015-01-22 16:25:25,764 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=3)'.
DEBUG 2015-01-22 16:25:25,764 (Z-Wave): Node                        : Node '2' : --> 'COMMAND_CLASS_CONFIGURATION', 'supported'.
DEBUG 2015-01-22 16:25:25,764 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=3)'...
DEBUG 2015-01-22 16:25:25,765 (Z-Wave): Node                        : Node '2' : --> 'COMMAND_CLASS_BASIC', 'supported'.
DEBUG 2015-01-22 16:25:25,765 (Z-Wave): Node                        : Node '2' : --> 'COMMAND_CLASS_MANUFACTURER_SPECIFIC', 'supported'.
DEBUG 2015-01-22 16:25:25,765 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x04, 0x00, 0x41, 0x03, 0xB9].
DEBUG 2015-01-22 16:25:25,766 (Z-Wave): Node                        : Node '2' : --> 'COMMAND_CLASS_ASSOCIATION_V2', 'supported'.
DEBUG 2015-01-22 16:25:25,766 (Z-Wave): Node                        : Node '2' : --> 'COMMAND_CLASS_VERSION', 'supported'.
DEBUG 2015-01-22 16:25:25,767 (Z-Wave): Node                        : Node '2' : --> 'COMMAND_CLASS_SWITCH_MULTILEVEL', 'supported'.
DEBUG 2015-01-22 16:25:25,767 (Z-Wave): Node                        : Node '2' : --> 'COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION_V2', 'supported'.
DEBUG 2015-01-22 16:25:25,768 (Z-Wave): Node                        : Node '2' : --> 'COMMAND_CLASS_SWITCH_MULTILEVEL', 'controlled'.
DEBUG 2015-01-22 16:25:25,771 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,773 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x04, 0x00, 0x41, 0x03, 0xB9] transmission OK.
DEBUG 2015-01-22 16:25:25,774 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=3)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,774 (Z-Wave): Node_Initializer            : Node '2' : [COMMAND_CLASS_NO_OPERATION (Retry='1')]...
DEBUG 2015-01-22 16:25:25,775 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,776 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x01, 0x41, 0xD3, 0x9C, 0x00, 0x04, 0x10, 0x01, 0xEC]
DEBUG 2015-01-22 16:25:25,779 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x01, 0x41, 0xD3, 0x9C, 0x00, 0x04, 0x10, 0x01, 0xEC] has been received.
DEBUG 2015-01-22 16:25:25,782 (Z-Wave): RX_Session_Layer            : Response received [0x01, 0x09, 0x01, 0x41, 0xD3, 0x9C, 0x00, 0x04, 0x10, 0x01, 0xEC] -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=3)' successfully executed.
DEBUG 2015-01-22 16:25:25,794 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_SendData(NodeID=2, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:25,794 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_SendData(NodeID=2, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:25,795 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=2, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-22 16:25:25,802 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=2, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x01)' request (callback ID=0x01).
DEBUG 2015-01-22 16:25:25,804 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x08, 0x00, 0x13, 0x02, 0x01, 0x00, 0x05, 0x01, 0xE3].
DEBUG 2015-01-22 16:25:25,815 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,818 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x08, 0x00, 0x13, 0x02, 0x01, 0x00, 0x05, 0x01, 0xE3] transmission OK.
DEBUG 2015-01-22 16:25:25,819 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,820 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=2, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x01)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,821 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:25,825 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:25,826 (Z-Wave): Node                        : Node '3' : Node has been successfully initialized with the following command classes...
DEBUG 2015-01-22 16:25:25,827 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_MULTI_CHANNEL_V2', 'supported'.
DEBUG 2015-01-22 16:25:25,827 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_CONFIGURATION', 'supported'.
DEBUG 2015-01-22 16:25:25,828 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_BASIC', 'supported'.
DEBUG 2015-01-22 16:25:25,829 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_MANUFACTURER_SPECIFIC', 'supported'.
DEBUG 2015-01-22 16:25:25,829 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x01).
DEBUG 2015-01-22 16:25:25,829 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_SWITCH_BINARY', 'supported'.
INFO 2015-01-22 16:25:25,829 (Z-Wave):  Controller                  : Z-Wave node info (ID=3) : [[Device Classes : 'BASIC_TYPE_ROUTING_SLAVE','GENERIC_TYPE_SWITCH_BINARY','SPECIFIC_TYPE_POWER_SWITCH_BINARY'],[Capabilities : 'NODEINFO_CAPABILITY_ROUTING','NODEINFO_CAPABILITY_LISTENING'],[Security : 'NODEINFO_SECURITY_OPTIONAL_FUNC_SUPPORT']].
DEBUG 2015-01-22 16:25:25,830 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,830 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_ASSOCIATION_V2', 'supported'.
DEBUG 2015-01-22 16:25:25,831 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_VERSION', 'supported'.
DEBUG 2015-01-22 16:25:25,832 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_MULTI_INSTANCE_ASSOCIATION', 'supported'.
DEBUG 2015-01-22 16:25:25,832 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x01, 0x00, 0xE8]
DEBUG 2015-01-22 16:25:25,834 (Z-Wave): Controller                  : Retrieving Z-Wave node info (ID=4)...
DEBUG 2015-01-22 16:25:25,834 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_MULTI_CHANNEL_V2', 'controlled'.
DEBUG 2015-01-22 16:25:25,834 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=4)'.
DEBUG 2015-01-22 16:25:25,834 (Z-Wave): Node                        : Node '3' : --> 'COMMAND_CLASS_SWITCH_BINARY', 'controlled'.
DEBUG 2015-01-22 16:25:25,835 (Z-Wave): RX_Session_Layer            : Waiting 64,963 [ms] for 'ZW_SendData' callback frame (callback ID=0x01).
DEBUG 2015-01-22 16:25:25,835 (Z-Wave): Node_Initializer            : Node '3' : [COMMAND_CLASS_NO_OPERATION (Retry='1')]...
DEBUG 2015-01-22 16:25:25,837 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x01, 0x00, 0xE8] has been received.
DEBUG 2015-01-22 16:25:25,837 (Z-Wave): Controller_API              : Enqueue (queue count=2) Z-Wave function call 'ZW_SendData(NodeID=3, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:25,841 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x01, 0x00, 0xE8] (callback ID=0x01) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=2, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x01)' successfully executed.
DEBUG 2015-01-22 16:25:25,844 (Z-Wave): Node_Initializer            : Node '2' : [COMMAND_CLASS_NO_OPERATION (Retry='1')] succeeded.
DEBUG 2015-01-22 16:25:25,844 (Z-Wave): Controller_API              : Dequeue (queue count=1) Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=4)'.
DEBUG 2015-01-22 16:25:25,845 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_GetNodeProtocolInfo(NodeID=4)'...
DEBUG 2015-01-22 16:25:25,845 (Z-Wave): Node_Initializer            : Node '2' : ====== Update dynamic data of command classes...
DEBUG 2015-01-22 16:25:25,846 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x04, 0x00, 0x41, 0x04, 0xBE].
DEBUG 2015-01-22 16:25:25,852 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,853 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,854 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x04, 0x00, 0x41, 0x04, 0xBE] transmission OK.
DEBUG 2015-01-22 16:25:25,854 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=4)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,855 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x01, 0x41, 0xD3, 0x9C, 0x01, 0x04, 0x08, 0x06, 0xF2]
DEBUG 2015-01-22 16:25:25,857 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x01, 0x41, 0xD3, 0x9C, 0x01, 0x04, 0x08, 0x06, 0xF2] has been received.
DEBUG 2015-01-22 16:25:25,859 (Z-Wave): RX_Session_Layer            : Response received [0x01, 0x09, 0x01, 0x41, 0xD3, 0x9C, 0x01, 0x04, 0x08, 0x06, 0xF2] -> Z-Wave function 'ZW_GetNodeProtocolInfo(NodeID=4)' successfully executed.
DEBUG 2015-01-22 16:25:25,866 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_SendData(NodeID=3, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:25,867 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=3, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-22 16:25:25,868 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x02)' request (callback ID=0x02).
DEBUG 2015-01-22 16:25:25,870 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x08, 0x00, 0x13, 0x03, 0x01, 0x00, 0x05, 0x02, 0xE1].
DEBUG 2015-01-22 16:25:25,888 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,891 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x08, 0x00, 0x13, 0x03, 0x01, 0x00, 0x05, 0x02, 0xE1] transmission OK.
DEBUG 2015-01-22 16:25:25,892 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x02)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,892 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,894 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:25,895 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:25,896 (Z-Wave): Node_Initializer            : Node '2' : ------ Update dynamic data of : [COMMAND_CLASS_CONFIGURATION]
DEBUG 2015-01-22 16:25:25,896 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x02).
DEBUG 2015-01-22 16:25:25,897 (Z-Wave): Node_Initializer            : Node '2' : ------ Update dynamic data of : [COMMAND_CLASS_BASIC]
DEBUG 2015-01-22 16:25:25,897 (Z-Wave): RX_Session_Layer            : Waiting 64,971 [ms] for 'ZW_SendData' callback frame (callback ID=0x02).
DEBUG 2015-01-22 16:25:25,898 (Z-Wave): Node_Initializer            : Node '2:0' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')]
DEBUG 2015-01-22 16:25:25,904 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,906 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x02, 0x00, 0xEB]
DEBUG 2015-01-22 16:25:25,907 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x02, 0x00, 0xEB] has been received.
DEBUG 2015-01-22 16:25:25,909 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x02, 0x00, 0xEB] (callback ID=0x02) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x02)' successfully executed.
DEBUG 2015-01-22 16:25:25,916 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_SendData(NodeID=2, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:25,918 (Z-Wave): Node_Initializer            : Node '3' : [COMMAND_CLASS_NO_OPERATION (Retry='1')] succeeded.
DEBUG 2015-01-22 16:25:25,919 (Z-Wave): Node_Initializer            : Node '3' : ====== Update dynamic data of command classes...
DEBUG 2015-01-22 16:25:25,919 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_CHANNEL_V2]
DEBUG 2015-01-22 16:25:25,919 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_SendData(NodeID=2, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:25,920 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_CONFIGURATION]
DEBUG 2015-01-22 16:25:25,920 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_BASIC]
DEBUG 2015-01-22 16:25:25,921 (Z-Wave): Node_Initializer            : Node '3:0' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')]
DEBUG 2015-01-22 16:25:25,921 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=2, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-22 16:25:25,922 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_SendData(NodeID=3, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:25,924 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=2, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x03)' request (callback ID=0x03).
DEBUG 2015-01-22 16:25:25,926 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x20, 0x02, 0x05, 0x03, 0xC1].
DEBUG 2015-01-22 16:25:25,929 (Z-Wave): Node                        : Node '4' : Node has been successfully initialized with the following command classes...
INFO 2015-01-22 16:25:25,929 (Z-Wave):  Controller                  : Z-Wave node info (ID=4) : [[Device Classes : 'BASIC_TYPE_ROUTING_SLAVE','GENERIC_TYPE_THERMOSTAT','SPECIFIC_TYPE_THERMOSTAT_GENERAL_V2'],[Capabilities : 'NODEINFO_CAPABILITY_ROUTING','NODEINFO_CAPABILITY_LISTENING'],[Security : 'NODEINFO_SECURITY_OPTIONAL_FUNC_SUPPORT']].
DEBUG 2015-01-22 16:25:25,930 (Z-Wave): Node                        : Node '4' : --> 'COMMAND_CLASS_MULTI_CHANNEL_V2', 'supported'.
DEBUG 2015-01-22 16:25:25,930 (Z-Wave): Controller                  : Configuring Z-Wave controller as SUC...
DEBUG 2015-01-22 16:25:25,930 (Z-Wave): Node                        : Node '4' : --> 'COMMAND_CLASS_CONFIGURATION', 'supported'.
DEBUG 2015-01-22 16:25:25,930 (Z-Wave): Node                        : Node '4' : --> 'COMMAND_CLASS_BASIC', 'supported'.
DEBUG 2015-01-22 16:25:25,931 (Z-Wave): Node                        : Node '4' : --> 'COMMAND_CLASS_SENSOR_MULTILEVEL', 'supported'.
DEBUG 2015-01-22 16:25:25,932 (Z-Wave): Node                        : Node '4' : --> 'COMMAND_CLASS_MANUFACTURER_SPECIFIC', 'supported'.
DEBUG 2015-01-22 16:25:25,932 (Z-Wave): Node                        : Node '4' : --> 'COMMAND_CLASS_ASSOCIATION', 'supported'.
DEBUG 2015-01-22 16:25:25,932 (Z-Wave): Node                        : Node '4' : --> 'COMMAND_CLASS_VERSION', 'supported'.
DEBUG 2015-01-22 16:25:25,933 (Z-Wave): Node                        : Node '4' : --> 'COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION_V2', 'supported'.
DEBUG 2015-01-22 16:25:25,933 (Z-Wave): Node_Initializer            : Node '4' : [COMMAND_CLASS_NO_OPERATION (Retry='1')]...
DEBUG 2015-01-22 16:25:25,934 (Z-Wave): Controller_API              : Enqueue (queue count=2) Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:25,945 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:25,947 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x20, 0x02, 0x05, 0x03, 0xC1] transmission OK.
DEBUG 2015-01-22 16:25:25,948 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=2, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x03)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:25,949 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,950 (Z-Wave): Controller_API              : Enqueue (queue count=3) Z-Wave function call 'ZW_SetSUCNodeID(NodeID=1, IsLocalNode=true, State=ENABLE_SUC, TxOption=LOW_POWER, Capability=ZW_SUC_FUNC_NODEID_SERVER)'.
DEBUG 2015-01-22 16:25:25,950 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:25,951 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:25,953 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x03).
DEBUG 2015-01-22 16:25:25,955 (Z-Wave): RX_Session_Layer            : Waiting 64,967 [ms] for 'ZW_SendData' callback frame (callback ID=0x03).
DEBUG 2015-01-22 16:25:25,961 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,962 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x03, 0x00, 0xEA]
DEBUG 2015-01-22 16:25:25,964 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x03, 0x00, 0xEA] has been received.
DEBUG 2015-01-22 16:25:25,966 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x03, 0x00, 0xEA] (callback ID=0x03) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=2, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x03)' successfully executed.
DEBUG 2015-01-22 16:25:25,971 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=2, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x03)
DEBUG 2015-01-22 16:25:25,972 (Z-Wave): Controller_API              : Dequeue (queue count=2) Z-Wave function call 'ZW_SendData(NodeID=3, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:25,973 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=3, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-22 16:25:25,974 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x05)' request (callback ID=0x05).
DEBUG 2015-01-22 16:25:25,974 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:25,976 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x00, 0x04, 0x00, 0x02, 0x03, 0x20, 0x03, 0x00, 0xD0]
DEBUG 2015-01-22 16:25:25,976 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x05, 0xC6].
DEBUG 2015-01-22 16:25:25,978 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x00, 0x04, 0x00, 0x02, 0x03, 0x20, 0x03, 0x00, 0xD0] has been received.
DEBUG 2015-01-22 16:25:25,983 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='2', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_BASIC', Command='0x03', Parameters: [0x00]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x05)'.
DEBUG 2015-01-22 16:25:25,986 (Z-Wave): Node_Initializer            : Node '2:0' : [COMMAND_CLASS_BASIC::BASIC_REPORT (Retry='1'), Value='0']
DEBUG 2015-01-22 16:25:25,987 (Z-Wave): Node_Initializer            : Node '2' : ------ Update dynamic data of : [COMMAND_CLASS_MANUFACTURER_SPECIFIC]
DEBUG 2015-01-22 16:25:25,988 (Z-Wave): Node_Initializer            : Node '2' : ------ Update dynamic data of : [COMMAND_CLASS_ASSOCIATION_V2]
DEBUG 2015-01-22 16:25:25,988 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x18]
DEBUG 2015-01-22 16:25:25,988 (Z-Wave): Node_Initializer            : Node '2' : ------ Update dynamic data of : [COMMAND_CLASS_VERSION]
DEBUG 2015-01-22 16:25:25,989 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=2, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x03)
DEBUG 2015-01-22 16:25:25,989 (Z-Wave): Node_Initializer            : Node '2' : ------ Update dynamic data of : [COMMAND_CLASS_SWITCH_MULTILEVEL]
DEBUG 2015-01-22 16:25:25,990 (Z-Wave): Node_Initializer            : Node '2:0' : [COMMAND_CLASS_SWITCH_MULTILEVEL::SWITCH_MULTILEVEL_GET (Retry='1')]
DEBUG 2015-01-22 16:25:25,991 (Z-Wave): RX_Frame_Layer              : CAN -> Failed to send data frame [0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x05, 0xC6] because the Z-Wave firmware has an ACK expected instead of the sent frame
DEBUG 2015-01-22 16:25:25,992 (Z-Wave): TX_Frame_Layer              : CAN -> Wait 100 [ms] before next retransmission attempt.
DEBUG 2015-01-22 16:25:25,997 (Z-Wave): Controller_API              : Enqueue (queue count=3) Z-Wave function call 'ZW_SendData(NodeID=2, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:26,102 (Z-Wave): TX_Frame_Layer              : Retransmission attempt 1, data frame [0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x05, 0xC6]
DEBUG 2015-01-22 16:25:26,114 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:26,116 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x20, 0x02, 0x05, 0x05, 0xC6] transmission OK.
DEBUG 2015-01-22 16:25:26,117 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x05)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:26,118 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,119 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:26,120 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:26,121 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x05).
DEBUG 2015-01-22 16:25:26,122 (Z-Wave): RX_Session_Layer            : Waiting 64,852 [ms] for 'ZW_SendData' callback frame (callback ID=0x05).
DEBUG 2015-01-22 16:25:26,130 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,131 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x05, 0x00, 0xEC]
DEBUG 2015-01-22 16:25:26,133 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x05, 0x00, 0xEC] has been received.
DEBUG 2015-01-22 16:25:26,134 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x05, 0x00, 0xEC] (callback ID=0x05) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x05)' successfully executed.
DEBUG 2015-01-22 16:25:26,136 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=3, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x05)
DEBUG 2015-01-22 16:25:26,136 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,137 (Z-Wave): Controller_API              : Dequeue (queue count=2) Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:26,138 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x20, 0x03, 0x00, 0xD1]
DEBUG 2015-01-22 16:25:26,138 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-22 16:25:26,139 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x06)' request (callback ID=0x06).
DEBUG 2015-01-22 16:25:26,140 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x20, 0x03, 0x00, 0xD1] has been received.
DEBUG 2015-01-22 16:25:26,141 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x08, 0x00, 0x13, 0x04, 0x01, 0x00, 0x05, 0x06, 0xE2].
DEBUG 2015-01-22 16:25:26,142 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='3', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_BASIC', Command='0x03', Parameters: [0x00]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x06)'.
DEBUG 2015-01-22 16:25:26,145 (Z-Wave): Node_Initializer            : Node '3:0' : [COMMAND_CLASS_BASIC::BASIC_REPORT (Retry='1'), Value='0']
DEBUG 2015-01-22 16:25:26,145 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=3, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x05)
DEBUG 2015-01-22 16:25:26,146 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_MANUFACTURER_SPECIFIC]
DEBUG 2015-01-22 16:25:26,146 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_SWITCH_BINARY]
DEBUG 2015-01-22 16:25:26,147 (Z-Wave): Node_Initializer            : Node '3:0' : [COMMAND_CLASS_SWITCH_BINARY::SWITCH_BINARY_GET (Retry='1')]
DEBUG 2015-01-22 16:25:26,152 (Z-Wave): Controller_API              : Enqueue (queue count=3) Z-Wave function call 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:26,155 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:26,157 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x08, 0x00, 0x13, 0x04, 0x01, 0x00, 0x05, 0x06, 0xE2] transmission OK.
DEBUG 2015-01-22 16:25:26,158 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x06)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:26,159 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,160 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:26,161 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:26,163 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x06).
DEBUG 2015-01-22 16:25:26,163 (Z-Wave): RX_Session_Layer            : Waiting 64,976 [ms] for 'ZW_SendData' callback frame (callback ID=0x06).
DEBUG 2015-01-22 16:25:26,174 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,175 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x06, 0x00, 0xEF]
DEBUG 2015-01-22 16:25:26,177 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x06, 0x00, 0xEF] has been received.
DEBUG 2015-01-22 16:25:26,178 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x06, 0x00, 0xEF] (callback ID=0x06) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x00], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x06)' successfully executed.
DEBUG 2015-01-22 16:25:26,180 (Z-Wave): Node_Initializer            : Node '4' : [COMMAND_CLASS_NO_OPERATION (Retry='1')] succeeded.
DEBUG 2015-01-22 16:25:26,180 (Z-Wave): Node_Initializer            : Node '4' : ====== Update dynamic data of command classes...
DEBUG 2015-01-22 16:25:26,180 (Z-Wave): Controller_API              : Dequeue (queue count=2) Z-Wave function call 'ZW_SetSUCNodeID(NodeID=1, IsLocalNode=true, State=ENABLE_SUC, TxOption=LOW_POWER, Capability=ZW_SUC_FUNC_NODEID_SERVER)'.
DEBUG 2015-01-22 16:25:26,181 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_CHANNEL_V2]
DEBUG 2015-01-22 16:25:26,181 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SetSUCNodeID(NodeID=1, IsLocalNode=true, State=ENABLE_SUC, TxOption=LOW_POWER, Capability=ZW_SUC_FUNC_NODEID_SERVER)'...
DEBUG 2015-01-22 16:25:26,181 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_CONFIGURATION]
DEBUG 2015-01-22 16:25:26,182 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_BASIC]
DEBUG 2015-01-22 16:25:26,183 (Z-Wave): Node_Initializer            : Node '4:0' : [COMMAND_CLASS_BASIC::BASIC_GET (Retry='1')]
DEBUG 2015-01-22 16:25:26,183 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x08, 0x00, 0x54, 0x01, 0x01, 0x01, 0x01, 0x04, 0xA7].
DEBUG 2015-01-22 16:25:26,184 (Z-Wave): Controller_API              : Enqueue (queue count=3) Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:26,193 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:26,194 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,195 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x08, 0x00, 0x54, 0x01, 0x01, 0x01, 0x01, 0x04, 0xA7] transmission OK.
DEBUG 2015-01-22 16:25:26,195 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x54, 0x01, 0xAF]
DEBUG 2015-01-22 16:25:26,196 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SetSUCNodeID(NodeID=1, IsLocalNode=true, State=ENABLE_SUC, TxOption=LOW_POWER, Capability=ZW_SUC_FUNC_NODEID_SERVER)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:26,199 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x54, 0x01, 0xAF] has been received.
DEBUG 2015-01-22 16:25:26,204 (Z-Wave): Controller                  : The Z-Wave controller has been successfully configured as SUC.
DEBUG 2015-01-22 16:25:26,204 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-22 16:25:26,205 (Z-Wave): RX_Session_Layer            : Response received [0x01, 0x04, 0x01, 0x54, 0x01, 0xAF] -> Z-Wave function 'ZW_SetSUCNodeID(NodeID=1, IsLocalNode=true, State=ENABLE_SUC, TxOption=LOW_POWER, Capability=ZW_SUC_FUNC_NODEID_SERVER)' successfully executed.
DEBUG 2015-01-22 16:25:26,205 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '2', endPoint = '1'
DEBUG 2015-01-22 16:25:26,206 (Z-Wave): Command_Builder             : Z-Wave command: command = status
DEBUG 2015-01-22 16:25:26,207 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-22 16:25:26,207 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-22 16:25:26,207 (Z-Wave): Controller_API              : Dequeue (queue count=2) Z-Wave function call 'ZW_SendData(NodeID=2, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:26,209 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=2, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-22 16:25:26,210 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=2, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x07)' request (callback ID=0x07).
DEBUG 2015-01-22 16:25:26,213 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x26, 0x02, 0x05, 0x07, 0xC3].
DEBUG 2015-01-22 16:25:26,224 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:26,227 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x26, 0x02, 0x05, 0x07, 0xC3] transmission OK.
DEBUG 2015-01-22 16:25:26,228 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=2, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x07)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:26,237 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,238 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:26,240 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:26,241 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,242 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x07, 0x00, 0xEE]
DEBUG 2015-01-22 16:25:26,244 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x07).
DEBUG 2015-01-22 16:25:26,244 (Z-Wave): RX_Session_Layer            : Waiting 64,966 [ms] for 'ZW_SendData' callback frame (callback ID=0x07).
DEBUG 2015-01-22 16:25:26,246 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x07, 0x00, 0xEE] has been received.
DEBUG 2015-01-22 16:25:26,247 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,248 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x07, 0x00, 0xEE] (callback ID=0x07) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=2, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x07)' successfully executed.
DEBUG 2015-01-22 16:25:26,249 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x00, 0x04, 0x00, 0x02, 0x03, 0x26, 0x03, 0x00, 0xD6]
DEBUG 2015-01-22 16:25:26,249 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=2, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x07)
DEBUG 2015-01-22 16:25:26,250 (Z-Wave): Controller_API              : Dequeue (queue count=1) Z-Wave function call 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:26,251 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x00, 0x04, 0x00, 0x02, 0x03, 0x26, 0x03, 0x00, 0xD6] has been received.
DEBUG 2015-01-22 16:25:26,251 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-22 16:25:26,253 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x08)' request (callback ID=0x08).
DEBUG 2015-01-22 16:25:26,257 (Z-Wave): Node_Initializer            : Node '2:0' : [COMMAND_CLASS_SWITCH_MULTILEVEL::SWITCH_MULTILEVEL_REPORT (Retry='1'), Value='0']
DEBUG 2015-01-22 16:25:26,258 (Z-Wave): Node_Initializer            : Node '2' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION_V2]
INFO 2015-01-22 16:25:26,259 (Z-Wave): Node '2' : Node [Manufacturer='Fibargroup', Type='Universal Dimmer 500W FGD-211'] has been successfully initialized.
DEBUG 2015-01-22 16:25:26,259 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x25, 0x02, 0x05, 0x08, 0xCE].
DEBUG 2015-01-22 16:25:26,261 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=2, Payload=[0x26, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x07)
DEBUG 2015-01-22 16:25:26,270 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:26,272 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x25, 0x02, 0x05, 0x08, 0xCE] transmission OK.
DEBUG 2015-01-22 16:25:26,273 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x08)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:26,274 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,275 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:26,277 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:26,278 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x08).
DEBUG 2015-01-22 16:25:26,279 (Z-Wave): RX_Session_Layer            : Waiting 64,974 [ms] for 'ZW_SendData' callback frame (callback ID=0x08).
DEBUG 2015-01-22 16:25:26,286 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,287 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x08, 0x00, 0xE1]
DEBUG 2015-01-22 16:25:26,289 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x08, 0x00, 0xE1] has been received.
DEBUG 2015-01-22 16:25:26,291 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x08, 0x00, 0xE1] (callback ID=0x08) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x08)' successfully executed.
DEBUG 2015-01-22 16:25:26,292 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x08)
DEBUG 2015-01-22 16:25:26,293 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,294 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:26,295 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-22 16:25:26,296 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x25, 0x03, 0x00, 0xD4]
DEBUG 2015-01-22 16:25:26,297 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x09)' request (callback ID=0x09).
DEBUG 2015-01-22 16:25:26,298 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x25, 0x03, 0x00, 0xD4] has been received.
DEBUG 2015-01-22 16:25:26,299 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x20, 0x02, 0x05, 0x09, 0xCD].
DEBUG 2015-01-22 16:25:26,301 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='3', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SWITCH_BINARY', Command='0x03', Parameters: [0x00]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x09)'.
DEBUG 2015-01-22 16:25:26,304 (Z-Wave): Node_Initializer            : Node '3:0' : [COMMAND_CLASS_SWITCH_BINARY::SWITCH_BINARY_REPORT (Retry='1'), State='OFF']
DEBUG 2015-01-22 16:25:26,309 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_ASSOCIATION_V2]
DEBUG 2015-01-22 16:25:26,310 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_VERSION]
DEBUG 2015-01-22 16:25:26,310 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_INSTANCE_ASSOCIATION]
DEBUG 2015-01-22 16:25:26,311 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_SWITCH_BINARY]
DEBUG 2015-01-22 16:25:26,311 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:26,311 (Z-Wave): Node_Initializer            : Node '3:2' : [COMMAND_CLASS_SWITCH_BINARY::SWITCH_BINARY_GET (Retry='1')]
DEBUG 2015-01-22 16:25:26,313 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x08)
DEBUG 2015-01-22 16:25:26,315 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x20, 0x02, 0x05, 0x09, 0xCD] transmission OK.
DEBUG 2015-01-22 16:25:26,316 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x09)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:26,317 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@131b230'.
DEBUG 2015-01-22 16:25:26,318 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,319 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:26,320 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:26,321 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x09).
DEBUG 2015-01-22 16:25:26,322 (Z-Wave): RX_Session_Layer            : Waiting 64,974 [ms] for 'ZW_SendData' callback frame (callback ID=0x09).
DEBUG 2015-01-22 16:25:26,330 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,331 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x09, 0x00, 0xE0]
DEBUG 2015-01-22 16:25:26,333 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x09, 0x00, 0xE0] has been received.
DEBUG 2015-01-22 16:25:26,336 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x09, 0x00, 0xE0] (callback ID=0x09) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x09)' successfully executed.
DEBUG 2015-01-22 16:25:26,338 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x09)
DEBUG 2015-01-22 16:25:26,339 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@131b230'.
DEBUG 2015-01-22 16:25:26,339 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@131b230'...
DEBUG 2015-01-22 16:25:26,340 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0A)' request (callback ID=0x0A).
DEBUG 2015-01-22 16:25:26,341 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,343 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x09, 0x00, 0x04, 0x00, 0x04, 0x03, 0x20, 0x03, 0x00, 0xD6]
DEBUG 2015-01-22 16:25:26,345 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x25, 0x02, 0x05, 0x0A, 0xA3].
DEBUG 2015-01-22 16:25:26,348 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x09, 0x00, 0x04, 0x00, 0x04, 0x03, 0x20, 0x03, 0x00, 0xD6] has been received.
DEBUG 2015-01-22 16:25:26,351 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_BASIC', Command='0x03', Parameters: [0x00]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0A)'.
DEBUG 2015-01-22 16:25:26,352 (Z-Wave): Node_Initializer            : Node '4:0' : [COMMAND_CLASS_BASIC::BASIC_REPORT (Retry='1'), Value='0']
DEBUG 2015-01-22 16:25:26,354 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_SENSOR_MULTILEVEL]
DEBUG 2015-01-22 16:25:26,354 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=4, Payload=[0x20, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x09)
DEBUG 2015-01-22 16:25:26,354 (Z-Wave): Node_Initializer            : Node '4:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')]
DEBUG 2015-01-22 16:25:26,362 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:26,362 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x18]
DEBUG 2015-01-22 16:25:26,365 (Z-Wave): RX_Frame_Layer              : CAN -> Failed to send data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x25, 0x02, 0x05, 0x0A, 0xA3] because the Z-Wave firmware has an ACK expected instead of the sent frame
DEBUG 2015-01-22 16:25:26,366 (Z-Wave): TX_Frame_Layer              : CAN -> Wait 100 [ms] before next retransmission attempt.
DEBUG 2015-01-22 16:25:26,376 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-22 16:25:26,377 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '2', endPoint = '1'
DEBUG 2015-01-22 16:25:26,377 (Z-Wave): Command_Builder             : Z-Wave command: command = status
DEBUG 2015-01-22 16:25:26,377 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-22 16:25:26,377 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-22 16:25:26,416 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-22 16:25:26,416 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '4', endPoint = '1'
DEBUG 2015-01-22 16:25:26,416 (Z-Wave): Command_Builder             : Z-Wave command: command = AIR_TEMPERATURE_SCALE_CELSIUS
DEBUG 2015-01-22 16:25:26,417 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-22 16:25:26,417 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-22 16:25:26,475 (Z-Wave): TX_Frame_Layer              : Retransmission attempt 1, data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x25, 0x02, 0x05, 0x0A, 0xA3]
DEBUG 2015-01-22 16:25:26,479 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-22 16:25:26,479 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '3', endPoint = '2'
DEBUG 2015-01-22 16:25:26,480 (Z-Wave): Command_Builder             : Z-Wave command: command = status
DEBUG 2015-01-22 16:25:26,480 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-22 16:25:26,480 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-22 16:25:26,490 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:26,493 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x25, 0x02, 0x05, 0x0A, 0xA3] transmission OK.
DEBUG 2015-01-22 16:25:26,494 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,495 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0A)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:26,495 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:26,497 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:26,499 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0A).
DEBUG 2015-01-22 16:25:26,499 (Z-Wave): RX_Session_Layer            : Waiting 64,841 [ms] for 'ZW_SendData' callback frame (callback ID=0x0A).
DEBUG 2015-01-22 16:25:26,506 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,508 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0A, 0x00, 0xE3]
DEBUG 2015-01-22 16:25:26,510 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0A, 0x00, 0xE3] has been received.
DEBUG 2015-01-22 16:25:26,512 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0A, 0x00, 0xE3] (callback ID=0x0A) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0A)' successfully executed.
DEBUG 2015-01-22 16:25:26,513 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@131b230
DEBUG 2015-01-22 16:25:26,515 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'.
DEBUG 2015-01-22 16:25:26,516 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x00)'...
DEBUG 2015-01-22 16:25:26,517 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)' request (callback ID=0x0B).
DEBUG 2015-01-22 16:25:26,519 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x05, 0x0B, 0xD8].
DEBUG 2015-01-22 16:25:26,520 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,523 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0D, 0x00, 0x04, 0x00, 0x03, 0x07, 0x60, 0x0D, 0x02, 0x00, 0x25, 0x03, 0x00, 0xBB]
DEBUG 2015-01-22 16:25:26,524 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-22 16:25:26,525 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '4', endPoint = '2'
DEBUG 2015-01-22 16:25:26,525 (Z-Wave): Command_Builder             : Z-Wave command: command = AIR_TEMPERATURE_SCALE_CELSIUS
DEBUG 2015-01-22 16:25:26,525 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-22 16:25:26,526 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-22 16:25:26,527 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0D, 0x00, 0x04, 0x00, 0x03, 0x07, 0x60, 0x0D, 0x02, 0x00, 0x25, 0x03, 0x00, 0xBB] has been received.
DEBUG 2015-01-22 16:25:26,529 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='3', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x02, 0x00, 0x25, 0x03, 0x00]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)'.
DEBUG 2015-01-22 16:25:26,531 (Z-Wave): Node_Initializer            : Node '3:2' : [COMMAND_CLASS_SWITCH_BINARY::SWITCH_BINARY_REPORT (Retry='1'), State='OFF']
DEBUG 2015-01-22 16:25:26,531 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x18]
DEBUG 2015-01-22 16:25:26,532 (Z-Wave): Node_Initializer            : Node '3' : ------ Update dynamic data of : [COMMAND_CLASS_SWITCH_BINARY]
DEBUG 2015-01-22 16:25:26,536 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@131b230
DEBUG 2015-01-22 16:25:26,537 (Z-Wave): Node_Initializer            : Node '3:1' : [COMMAND_CLASS_SWITCH_BINARY::SWITCH_BINARY_GET (Retry='1')]
DEBUG 2015-01-22 16:25:26,537 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@da2c9a'.
DEBUG 2015-01-22 16:25:26,538 (Z-Wave): RX_Frame_Layer              : CAN -> Failed to send data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x05, 0x0B, 0xD8] because the Z-Wave firmware has an ACK expected instead of the sent frame
DEBUG 2015-01-22 16:25:26,539 (Z-Wave): TX_Frame_Layer              : CAN -> Wait 100 [ms] before next retransmission attempt.
DEBUG 2015-01-22 16:25:26,579 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-22 16:25:26,580 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '4', endPoint = '1'
DEBUG 2015-01-22 16:25:26,580 (Z-Wave): Command_Builder             : Z-Wave command: command = AIR_TEMPERATURE_SCALE_CELSIUS
DEBUG 2015-01-22 16:25:26,581 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-22 16:25:26,581 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-22 16:25:26,635 (Z-Wave): Command_Builder             : Building Z-Wave command
DEBUG 2015-01-22 16:25:26,636 (Z-Wave): Command_Builder             : Z-Wave command: nodeId = '3', endPoint = '1'
DEBUG 2015-01-22 16:25:26,637 (Z-Wave): Command_Builder             : Z-Wave command: command = status
DEBUG 2015-01-22 16:25:26,637 (Z-Wave): Command_Builder             : Z-Wave command: paramValue = null
DEBUG 2015-01-22 16:25:26,638 (Z-Wave): Command_Builder             : Z-Wave command created successfully
DEBUG 2015-01-22 16:25:26,646 (Z-Wave): TX_Frame_Layer              : Retransmission attempt 1, data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x05, 0x0B, 0xD8]
DEBUG 2015-01-22 16:25:26,658 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:26,660 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x31, 0x04, 0x05, 0x0B, 0xD8] transmission OK.
DEBUG 2015-01-22 16:25:26,662 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:26,663 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,664 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:26,666 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:26,667 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0B).
DEBUG 2015-01-22 16:25:26,668 (Z-Wave): RX_Session_Layer            : Waiting 64,849 [ms] for 'ZW_SendData' callback frame (callback ID=0x0B).
DEBUG 2015-01-22 16:25:26,677 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,679 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0B, 0x00, 0xE2]
DEBUG 2015-01-22 16:25:26,680 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0B, 0x00, 0xE2] has been received.
DEBUG 2015-01-22 16:25:26,683 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0B, 0x00, 0xE2] (callback ID=0x0B) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)' successfully executed.
DEBUG 2015-01-22 16:25:26,685 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)
DEBUG 2015-01-22 16:25:26,685 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@da2c9a'.
DEBUG 2015-01-22 16:25:26,686 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@da2c9a'...
DEBUG 2015-01-22 16:25:26,687 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0C)' request (callback ID=0x0C).
DEBUG 2015-01-22 16:25:26,689 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,691 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x5A]
DEBUG 2015-01-22 16:25:26,693 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x25, 0x02, 0x05, 0x0C, 0xA6].
DEBUG 2015-01-22 16:25:26,696 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x5A] has been received.
DEBUG 2015-01-22 16:25:26,698 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_MULTILEVEL', Command='0x05', Parameters: [0x01, 0x42, 0x08, 0xD0]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0C)'.
DEBUG 2015-01-22 16:25:26,706 (Z-Wave): Node_Initializer            : Node '4:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT (Retry='1'), Value='22.56', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:25:26,707 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0B)
DEBUG 2015-01-22 16:25:26,708 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_MANUFACTURER_SPECIFIC]
DEBUG 2015-01-22 16:25:26,708 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_ASSOCIATION]
DEBUG 2015-01-22 16:25:26,709 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_VERSION]
DEBUG 2015-01-22 16:25:26,709 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION_V2]
DEBUG 2015-01-22 16:25:26,710 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_SENSOR_MULTILEVEL]
DEBUG 2015-01-22 16:25:26,710 (Z-Wave): Node_Initializer            : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')]
DEBUG 2015-01-22 16:25:26,711 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1108aa5'.
DEBUG 2015-01-22 16:25:26,711 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x18]
DEBUG 2015-01-22 16:25:26,714 (Z-Wave): RX_Frame_Layer              : CAN -> Failed to send data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x25, 0x02, 0x05, 0x0C, 0xA6] because the Z-Wave firmware has an ACK expected instead of the sent frame
DEBUG 2015-01-22 16:25:26,715 (Z-Wave): TX_Frame_Layer              : CAN -> Wait 100 [ms] before next retransmission attempt.
DEBUG 2015-01-22 16:25:26,823 (Z-Wave): TX_Frame_Layer              : Retransmission attempt 1, data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x25, 0x02, 0x05, 0x0C, 0xA6]
DEBUG 2015-01-22 16:25:26,844 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:26,847 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x03, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x25, 0x02, 0x05, 0x0C, 0xA6] transmission OK.
DEBUG 2015-01-22 16:25:26,849 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,850 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:26,851 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0C)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:26,853 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:26,854 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0C).
DEBUG 2015-01-22 16:25:26,855 (Z-Wave): RX_Session_Layer            : Waiting 64,832 [ms] for 'ZW_SendData' callback frame (callback ID=0x0C).
DEBUG 2015-01-22 16:25:26,861 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,863 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0C, 0x00, 0xE5]
DEBUG 2015-01-22 16:25:26,866 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0C, 0x00, 0xE5] has been received.
DEBUG 2015-01-22 16:25:26,868 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0C, 0x00, 0xE5] (callback ID=0x0C) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=3, Payload=[0x25, 0x02], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0C)' successfully executed.
DEBUG 2015-01-22 16:25:26,869 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@da2c9a
DEBUG 2015-01-22 16:25:26,869 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:26,870 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1108aa5'.
DEBUG 2015-01-22 16:25:26,870 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1108aa5'...
DEBUG 2015-01-22 16:25:26,872 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0D, 0x00, 0x04, 0x00, 0x03, 0x07, 0x60, 0x0D, 0x01, 0x00, 0x25, 0x03, 0x00, 0xB8]
DEBUG 2015-01-22 16:25:26,872 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)' request (callback ID=0x0D).
DEBUG 2015-01-22 16:25:26,874 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x31, 0x04, 0x05, 0x0D, 0xB1].
DEBUG 2015-01-22 16:25:26,878 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0D, 0x00, 0x04, 0x00, 0x03, 0x07, 0x60, 0x0D, 0x01, 0x00, 0x25, 0x03, 0x00, 0xB8] has been received.
DEBUG 2015-01-22 16:25:26,881 (Z-Wave): RX_Session_Layer            : Received an unexpected frame [APP_CMD_HANDLER_FRAME : NodeID='3', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x01, 0x00, 0x25, 0x03, 0x00]] while waiting for an ACK of the Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)'.
DEBUG 2015-01-22 16:25:26,911 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@da2c9a
DEBUG 2015-01-22 16:25:26,911 (Z-Wave): Node_Initializer            : Node '3:1' : [COMMAND_CLASS_SWITCH_BINARY::SWITCH_BINARY_REPORT (Retry='1'), State='OFF']
INFO 2015-01-22 16:25:26,912 (Z-Wave): Node '3' : Node [Manufacturer='Fibargroup', Type='Double Relay Switch FGS-221'] has been successfully initialized.
DEBUG 2015-01-22 16:25:26,928 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x18]
DEBUG 2015-01-22 16:25:26,931 (Z-Wave): RX_Frame_Layer              : CAN -> Failed to send data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x31, 0x04, 0x05, 0x0D, 0xB1] because the Z-Wave firmware has an ACK expected instead of the sent frame
DEBUG 2015-01-22 16:25:26,932 (Z-Wave): TX_Frame_Layer              : CAN -> Wait 100 [ms] before next retransmission attempt.
DEBUG 2015-01-22 16:25:27,039 (Z-Wave): TX_Frame_Layer              : Retransmission attempt 1, data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x31, 0x04, 0x05, 0x0D, 0xB1]
DEBUG 2015-01-22 16:25:27,054 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:27,057 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x31, 0x04, 0x05, 0x0D, 0xB1] transmission OK.
DEBUG 2015-01-22 16:25:27,058 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,059 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:27,059 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:27,061 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:27,063 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0D).
DEBUG 2015-01-22 16:25:27,064 (Z-Wave): RX_Session_Layer            : Waiting 64,808 [ms] for 'ZW_SendData' callback frame (callback ID=0x0D).
DEBUG 2015-01-22 16:25:27,074 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,075 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0D, 0x00, 0xE4]
DEBUG 2015-01-22 16:25:27,077 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0D, 0x00, 0xE4] has been received.
DEBUG 2015-01-22 16:25:27,079 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0D, 0x00, 0xE4] (callback ID=0x0D) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)' successfully executed.
DEBUG 2015-01-22 16:25:27,080 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1108aa5
DEBUG 2015-01-22 16:25:27,087 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,090 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x25]
DEBUG 2015-01-22 16:25:27,094 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x25] has been received.
DEBUG 2015-01-22 16:25:27,095 (Z-Wave): Node_Initializer            : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT (Retry='1'), Value='22.56', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:25:27,104 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1108aa5
DEBUG 2015-01-22 16:25:27,106 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_SENSOR_MULTILEVEL]
DEBUG 2015-01-22 16:25:27,109 (Z-Wave): Node_Initializer            : Node '4:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')]
DEBUG 2015-01-22 16:25:27,110 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d'.
DEBUG 2015-01-22 16:25:27,110 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d'.
DEBUG 2015-01-22 16:25:27,111 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d'...
DEBUG 2015-01-22 16:25:27,112 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0E)' request (callback ID=0x0E).
DEBUG 2015-01-22 16:25:27,114 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x0E, 0xB1].
DEBUG 2015-01-22 16:25:27,130 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:27,133 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x0E, 0xB1] transmission OK.
DEBUG 2015-01-22 16:25:27,134 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,135 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0E)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:27,136 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:27,152 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:27,153 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,154 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0E).
DEBUG 2015-01-22 16:25:27,155 (Z-Wave): RX_Session_Layer            : Waiting 64,957 [ms] for 'ZW_SendData' callback frame (callback ID=0x0E).
DEBUG 2015-01-22 16:25:27,155 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0E, 0x00, 0xE7]
DEBUG 2015-01-22 16:25:27,162 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0E, 0x00, 0xE7] has been received.
DEBUG 2015-01-22 16:25:27,164 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,165 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0E, 0x00, 0xE7] (callback ID=0x0E) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0E)' successfully executed.
DEBUG 2015-01-22 16:25:27,167 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x26]
DEBUG 2015-01-22 16:25:27,168 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d
DEBUG 2015-01-22 16:25:27,170 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x26] has been received.
DEBUG 2015-01-22 16:25:27,172 (Z-Wave): Node_Initializer            : Node '4:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT (Retry='1'), Value='22.56', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:25:27,172 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d
INFO 2015-01-22 16:25:27,176 (Z-Wave): Node '4' : Node [Manufacturer='ConnectHome', Type='Unknown'] has been successfully initialized.
DEBUG 2015-01-22 16:25:26,928 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x18]
DEBUG 2015-01-22 16:25:26,931 (Z-Wave): RX_Frame_Layer              : CAN -> Failed to send data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x31, 0x04, 0x05, 0x0D, 0xB1] because the Z-Wave firmware has an ACK expected instead of the sent frame
DEBUG 2015-01-22 16:25:26,932 (Z-Wave): TX_Frame_Layer              : CAN -> Wait 100 [ms] before next retransmission attempt.
DEBUG 2015-01-22 16:25:27,039 (Z-Wave): TX_Frame_Layer              : Retransmission attempt 1, data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x31, 0x04, 0x05, 0x0D, 0xB1]
DEBUG 2015-01-22 16:25:27,054 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:27,057 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x02, 0x31, 0x04, 0x05, 0x0D, 0xB1] transmission OK.
DEBUG 2015-01-22 16:25:27,058 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,059 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:27,059 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:27,061 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:27,063 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0D).
DEBUG 2015-01-22 16:25:27,064 (Z-Wave): RX_Session_Layer            : Waiting 64,808 [ms] for 'ZW_SendData' callback frame (callback ID=0x0D).
DEBUG 2015-01-22 16:25:27,074 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,075 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0D, 0x00, 0xE4]
DEBUG 2015-01-22 16:25:27,077 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0D, 0x00, 0xE4] has been received.
DEBUG 2015-01-22 16:25:27,079 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0D, 0x00, 0xE4] (callback ID=0x0D) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0D)' successfully executed.
DEBUG 2015-01-22 16:25:27,080 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1108aa5
DEBUG 2015-01-22 16:25:27,087 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,090 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x25]
DEBUG 2015-01-22 16:25:27,094 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x25] has been received.
DEBUG 2015-01-22 16:25:27,095 (Z-Wave): Node_Initializer            : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT (Retry='1'), Value='22.56', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:25:27,104 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@1108aa5
DEBUG 2015-01-22 16:25:27,106 (Z-Wave): Node_Initializer            : Node '4' : ------ Update dynamic data of : [COMMAND_CLASS_SENSOR_MULTILEVEL]
DEBUG 2015-01-22 16:25:27,109 (Z-Wave): Node_Initializer            : Node '4:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_GET (Retry='1')]
DEBUG 2015-01-22 16:25:27,110 (Z-Wave): Controller_API              : Dequeue (queue count=0) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d'.
DEBUG 2015-01-22 16:25:27,110 (Z-Wave): Controller_API              : Enqueue (queue count=1) Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d'.
DEBUG 2015-01-22 16:25:27,111 (Z-Wave): TX_Session_Layer            : Executing Z-Wave function call 'org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d'...
DEBUG 2015-01-22 16:25:27,112 (Z-Wave): TX_Session_Layer            : Send (repeat count=1) Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0E)' request (callback ID=0x0E).
DEBUG 2015-01-22 16:25:27,114 (Z-Wave): TX_Frame_Layer              : Send data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x0E, 0xB1].
DEBUG 2015-01-22 16:25:27,130 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x06]
DEBUG 2015-01-22 16:25:27,133 (Z-Wave): RX_Frame_Layer              : ACK -> Data frame [0x01, 0x0D, 0x00, 0x13, 0x04, 0x06, 0x60, 0x0D, 0x00, 0x01, 0x31, 0x04, 0x05, 0x0E, 0xB1] transmission OK.
DEBUG 2015-01-22 16:25:27,134 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,135 (Z-Wave): RX_Session_Layer            : ACK -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0E)' has been successfully sent - waiting 5,000 [ms] for response frame.
DEBUG 2015-01-22 16:25:27,136 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x04, 0x01, 0x13, 0x01, 0xE8]
DEBUG 2015-01-22 16:25:27,152 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] has been received.
DEBUG 2015-01-22 16:25:27,153 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,154 (Z-Wave): RX_Session_Layer            : 'ZW_SendData' response [0x01, 0x04, 0x01, 0x13, 0x01, 0xE8] with status 'RETURN_VALUE_TRUE' received (callback ID=0x0E).
DEBUG 2015-01-22 16:25:27,155 (Z-Wave): RX_Session_Layer            : Waiting 64,957 [ms] for 'ZW_SendData' callback frame (callback ID=0x0E).
DEBUG 2015-01-22 16:25:27,155 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x05, 0x00, 0x13, 0x0E, 0x00, 0xE7]
DEBUG 2015-01-22 16:25:27,162 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x05, 0x00, 0x13, 0x0E, 0x00, 0xE7] has been received.
DEBUG 2015-01-22 16:25:27,164 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:25:27,165 (Z-Wave): RX_Session_Layer            : Callback [0x01, 0x05, 0x00, 0x13, 0x0E, 0x00, 0xE7] (callback ID=0x0E) with state 'TRANSMIT_COMPLETE_OK' received -> Z-Wave function 'ZW_SendData(NodeID=4, Payload=[0x31, 0x04], TxOptions=[TRANSMIT_OPTION_AUTO_ROUTE, TRANSMIT_EXPLORE_OPTION_ACK], CallbackID=0x0E)' successfully executed.
DEBUG 2015-01-22 16:25:27,167 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x26]
DEBUG 2015-01-22 16:25:27,168 (Z-Wave): RX_Session_Layer            : Add function to pending transaction list (size='1') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d
DEBUG 2015-01-22 16:25:27,170 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x01, 0x00, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD0, 0x26] has been received.
DEBUG 2015-01-22 16:25:27,172 (Z-Wave): Node_Initializer            : Node '4:1' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT (Retry='1'), Value='22.56', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:25:27,172 (Z-Wave): RX_Session_Layer            : Removed function from pending transaction list (size='0') : org.openremote.controller.protocol.zwave.model.commandclasses.CCMultiChannelV2FuncDecorator@38797d
INFO 2015-01-22 16:25:27,176 (Z-Wave): Node '4' : Node [Manufacturer='ConnectHome', Type='Unknown'] has been successfully initialized.
DEBUG 2015-01-22 16:26:34,785 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:26:34,787 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xCA, 0x40]
DEBUG 2015-01-22 16:26:34,789 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xCA, 0x40] has been received.
DEBUG 2015-01-22 16:26:34,797 (Z-Wave): RX_Session_Layer            : Node '4' : [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_MULTILEVEL', Command='0x05', Parameters: [0x01, 0x42, 0x08, 0xCA]]
DEBUG 2015-01-22 16:26:34,806 (Z-Wave): CCMultiLevelSensor          : Node '4:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='22.5', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:26:34,809 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:26:34,811 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xCA, 0x3D]
DEBUG 2015-01-22 16:26:34,815 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xCA, 0x3D] has been received.
DEBUG 2015-01-22 16:26:34,818 (Z-Wave): RX_Session_Layer            : Node '4' : [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xCA]]
DEBUG 2015-01-22 16:26:34,819 (Z-Wave): CCMultiLevelSensor          : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='22.5', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:31:34,775 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:31:34,778 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6, 0x5C]
DEBUG 2015-01-22 16:31:34,780 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6, 0x5C] has been received.
DEBUG 2015-01-22 16:31:34,782 (Z-Wave): RX_Session_Layer            : Node '4' : [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_MULTILEVEL', Command='0x05', Parameters: [0x01, 0x42, 0x08, 0xD6]]
DEBUG 2015-01-22 16:31:34,783 (Z-Wave): CCMultiLevelSensor          : Node '4:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='22.62', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:31:34,799 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:31:34,801 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6, 0x21]
DEBUG 2015-01-22 16:31:34,805 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6, 0x21] has been received.
DEBUG 2015-01-22 16:31:34,807 (Z-Wave): RX_Session_Layer            : Node '4' : [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6]]
DEBUG 2015-01-22 16:31:34,808 (Z-Wave): CCMultiLevelSensor          : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='22.62', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:36:34,766 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:36:34,768 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6, 0x5C]
DEBUG 2015-01-22 16:36:34,770 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x0C, 0x00, 0x04, 0x00, 0x04, 0x06, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6, 0x5C] has been received.
DEBUG 2015-01-22 16:36:34,772 (Z-Wave): RX_Session_Layer            : Node '4' : [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_SENSOR_MULTILEVEL', Command='0x05', Parameters: [0x01, 0x42, 0x08, 0xD6]]
DEBUG 2015-01-22 16:36:34,773 (Z-Wave): CCMultiLevelSensor          : Node '4:0' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='22.62', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].
DEBUG 2015-01-22 16:36:34,790 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x01]
DEBUG 2015-01-22 16:36:34,793 (Z-Wave): RX_Serial_Port_RXTX         : Data bytes read : [0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6, 0x21]
DEBUG 2015-01-22 16:36:34,796 (Z-Wave): RX_Frame_Layer              : Data frame [0x01, 0x10, 0x00, 0x04, 0x00, 0x04, 0x0A, 0x60, 0x0D, 0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6, 0x21] has been received.
DEBUG 2015-01-22 16:36:34,798 (Z-Wave): RX_Session_Layer            : Node '4' : [APP_CMD_HANDLER_FRAME : NodeID='4', Status=[RECEIVE_STATUS_TYPE_SINGLE], CommandClass='COMMAND_CLASS_MULTI_INSTANCE', Command='0x0D', Parameters: [0x02, 0x02, 0x31, 0x05, 0x01, 0x42, 0x08, 0xD6]]
DEBUG 2015-01-22 16:36:34,799 (Z-Wave): CCMultiLevelSensor          : Node '4:2' : [COMMAND_CLASS_SENSOR_MULTILEVEL::SENSOR_MULTILEVEL_REPORT, Value='22.62', Type='AIR_TEMPERATURE', Scale='AIR_TEMPERATURE_SCALE_CELSIUS'].

node4.xml file

<?xml version="1.0" encoding="UTF-8"?>
<node id="4">
  <manufacturer id="377">ConnectHome</manufacturer>
  <basic-device-class id="0x04">BASIC_TYPE_ROUTING_SLAVE</basic-device-class>
  <generic-device-class id="0x08">GENERIC_TYPE_THERMOSTAT</generic-device-class>
  <specific-device-class id="0x06">SPECIFIC_TYPE_THERMOSTAT_GENERAL_V2</specific-device-class>
  <product-type id="0x0001" />
  <product id="0x0001" />
  <listening>true</listening>
  <routing>true</routing>
  <command-classes>
    <command-class id="0x60" version="2" name="COMMAND_CLASS_MULTI_CHANNEL_V2" type="supported" />
    <command-class id="0x70" version="1" name="COMMAND_CLASS_CONFIGURATION" type="supported" />
    <command-class id="0x20" version="1" name="COMMAND_CLASS_BASIC" type="supported" />
    <command-class id="0x31" version="1" name="COMMAND_CLASS_SENSOR_MULTILEVEL" type="supported">
      <sensors>
        <sensor id="0x01" name="AIR_TEMPERATURE">
          <scales>
            <scale id="0x00">AIR_TEMPERATURE_SCALE_CELSIUS</scale>
          </scales>
        </sensor>
      </sensors>
    </command-class>
    <command-class id="0x72" version="1" name="COMMAND_CLASS_MANUFACTURER_SPECIFIC" type="supported" />
    <command-class id="0x85" version="1" name="COMMAND_CLASS_ASSOCIATION" type="supported" />
    <command-class id="0x86" version="1" name="COMMAND_CLASS_VERSION" type="supported" />
    <command-class id="0x8E" version="2" name="COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION_V2" type="supported" />
  </command-classes>
  <endpoints>
    <endpoint number="1">
      <generic-device-class id="0x08">GENERIC_TYPE_THERMOSTAT</generic-device-class>
      <specific-device-class id="0x06">SPECIFIC_TYPE_THERMOSTAT_GENERAL_V2</specific-device-class>
      <command-classes>
        <command-class id="0x31" version="1" name="COMMAND_CLASS_SENSOR_MULTILEVEL" type="supported">
          <sensors>
            <sensor id="0x01" name="AIR_TEMPERATURE">
              <scales>
                <scale id="0x00">AIR_TEMPERATURE_SCALE_CELSIUS</scale>
              </scales>
            </sensor>
          </sensors>
        </command-class>
      </command-classes>
    </endpoint>
    <endpoint number="2">
      <generic-device-class id="0x21">GENERIC_TYPE_SENSOR_MULTILEVEL</generic-device-class>
      <specific-device-class id="0x01">SPECIFIC_TYPE_ROUTING_SENSOR_MULTILEVEL</specific-device-class>
      <command-classes>
        <command-class id="0x31" version="1" name="COMMAND_CLASS_SENSOR_MULTILEVEL" type="supported">
          <sensors>
            <sensor id="0x01" name="AIR_TEMPERATURE">
              <scales>
                <scale id="0x00">AIR_TEMPERATURE_SCALE_CELSIUS</scale>
              </scales>
            </sensor>
          </sensors>
        </command-class>
      </command-classes>
    </endpoint>
  </endpoints>
  <configuration hash="1073D747A1EC20A168A4AB4D471DEAB3">
    <associations>
      <association-group id="1" capacity="10">
        <association>
          <node>1</node>
        </association>
      </association-group>
      <association-group id="2" capacity="1" />
    </associations>
    <parameters />
  </configuration>
</node>

Received from the manufacturer ConnectHome, command classes to the thermostat CH201.

THERMOSTAT_MODE
THERMOSTAT_SETPOINT
THERMOSTAT_OPERATING_STATE
BASIC
SENSOR_MULTILEVEL
MULTI_CHANNEL
ASSOSIATIONS
MULTI_CHANNEL_ASSOSIATIONS
CONFIGURATION

In SynoZWave:

In Domoticz:

Posted by aleksey_z at Jan 22, 2015 14:33

to RAINER HITZ

Good day.
Is there any news about the support Z-Wave COMMAND_CLASS_THERMOSTAT_SETPOINT
I can not turn the thermostat (the heating system of my Showroom). While not very cold, I use for heating air-conditioning (heating mode).
And if it is very cold, how to turn on the heating system? Only directly, without thermostat - not very good.

Or return to the previous version?

Posted by aleksey_z at Feb 03, 2015 10:48

I've made some progress but it's not finished yet but I could send you a test version if you are interested and also willing to play around.

I've implemented it according to the recommendations in the Z-Wave specification but it didn't work with my current test device. I'm interested to test if your device works with the recommended implementation.

Please send a pm to rainer at openremote dot org if you're interested.

Posted by rhitz at Feb 04, 2015 15:46

Thank you, replied to the email.

Posted by aleksey_z at Feb 04, 2015 16:02
Document generated by Confluence on Jun 05, 2016 09:39