Zwave2mqtt: Switch returns multiple conflicting changes

Created on 9 Mar 2019  Â·  38Comments  Â·  Source: OpenZWave/Zwave2Mqtt 
2019-03-09T14:04:28.547Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-03-09T14:04:28.547Z z2m:Zwave Success zwave api call setValue
2019-03-09T14:04:28.702Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> false
2019-03-09T14:04:28.724Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-03-09T14:04:30.424Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-03-09T14:04:35.068Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-03-09T14:04:35.068Z z2m:Zwave Success zwave api call setValue
2019-03-09T14:04:35.131Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-03-09T14:04:35.204Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-03-09T14:04:35.274Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-03-09T14:04:39.431Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true
2019-03-09T14:04:47.767Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-03-09T14:04:47.768Z z2m:Zwave Success zwave api call setValue
2019-03-09T14:04:47.962Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> false
2019-03-09T14:04:52.424Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-03-09T14:04:59.582Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-03-09T14:04:59.583Z z2m:Zwave Success zwave api call setValue
2019-03-09T14:04:59.692Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true
2019-03-09T14:05:04.472Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-03-09T14:05:04.514Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true

When using the toggle button on the UI it detects multie state changes. This is very hard to filter out on some MQTT clients as they also gets multiple changes.

Usually the first one is the correct one, but not always.

picture sjorge

Most helpful comment

Looks good!

picture sjorge on 8 Apr 2019
🎉1 👍1

All 38 comments

The problem is also present when using MQTT messages to change the switch value.

zwave/bedroom/socket_desk/switch_binary/switch/set with { "value": false }

will trigger the following messages on the bus

zwave/bedroom/socket_desk/switch_binary/switch {"time":1552141391294,"value":true}
zwave/bedroom/socket_desk/switch_binary/switch {"time":1552141391361,"value":true}
zwave/bedroom/socket_desk/switch_binary/switch {"time":1552141392427,"value":false}

sjorge picture sjorge on 9 Mar 2019

Are you using lastest versionfrom master branch?
Have you enable pooling of the switch state?
What is your Openzwave version?

This is strange and seems not a problem related to my library but more likely a wrong switch configuration in the protocol or a bug with Openzwave library because those events (like state changes and node status change) are triggered from the underlying libraries

robertsLando picture robertsLando on 11 Mar 2019

I am on the master branch for zwave2mqtt, openzwave version = master branch also (als the fix for illumos went in a few weeks ago), node-openzwave-shared = master branch also.

I have not enabled pooling? What is this and how do I try to enable it?

sjorge picture sjorge on 11 Mar 2019

Enable poll is used to get updates from a zwave device every poll interval seconds. Poll interval is global (it is the same for each device in the network) and when you enable poll of a value of a device you have to specify the intensity (that sincerly I still don't understand what intensity is used to since It isn't well documented and I can't see any difference between different intensity values, my thought is that values with higher intensity are polled before others but the difference in a small network can't be noticed)

robertsLando picture robertsLando on 12 Mar 2019

In your case I suggest to try to disable poll of that value by using the api disablePoll and check if it fix your problem

robertsLando picture robertsLando on 12 Mar 2019

I tried with both poll enable or disabled under gateway for that property, there was no difference.

Edit: I've been trying to dedup these in node-red but I am not having much luck, as the payload has a different timestamp. Sadly this causing all sorts of freak outs :( will need to look more into this.

sjorge picture sjorge on 13 Mar 2019

I remembered I did not do a restart after messing with adding the value under gateway settings and setting poll enabled or disabled...

So I set it to disabled again and restarted zwave2mqtt and got the error described in #17
Looks like polling is still enabled after a restart/controller softreset and I am unable to disable it again using the gateway settings!

sjorge picture sjorge on 13 Mar 2019

By adding a value in gateway table and set the enablePoll flag you can just enable poll but it if the flag is disabled it is not disabled (because sometimes it is enabled by default), I think the poll of that value is enabled in the device xml configuration so to disable it you have to use the mqtt api

Here you can find a list of all zwave apis

The api you need is: zwave.disablePoll({valueId});

To call it using mqtt check docs at: MQTT APIs section

With mosquitto:

mosquitto_pub -t zwave/_CLIENTS/ZWAVE_GATEWAY-test/api/disablePoll/set -m '{"args":[ { "node_id": 3, "class_id": 64, "instance": 2, "index": 0 } ] }'

Just remember to replace ZWAVE_GATEWAY-test with your mqtt gateway name and the valueid you need

robertsLando picture robertsLando on 14 Mar 2019

So I can enable and disable polling via API but the result is the same. Well with polling I get them every X seconds depending on the value in frequancy which is a bit weird because there seems to be no logic to that.

Sadly this does also not fix the problem as it is still the same story

  • set switch state
  • switch state update (old value)
  • switch state update (old value)
  • switch state update (new value)
  • switch state update (new value)

Sometimes between there is one more old or new value depending on where the polled one comes in :/

Some settings can be tweaked on the device so it does not send state changes when the switch is changed (not what I want... but I enabled it as a test), then it gets enve worse I just get the 2 updates with the old value and none with the new.

sjorge picture sjorge on 14 Mar 2019

I changed the payload to get all the data instead of just the timed value.

Turning off the switch

{"value_id":"4-37-1-0","node_id":4,"class_id":37,"type":"bool","genre":"user","instance":1,"index":0,"label":"Switch","units":"","help":"","read_only":false,"write_only":false,"min":0,"max":0,"is_polled":false,"value":true}
{"value_id":"4-37-1-0","node_id":4,"class_id":37,"type":"bool","genre":"user","instance":1,"index":0,"label":"Switch","units":"","help":"","read_only":false,"write_only":false,"min":0,"max":0,"is_polled":false,"value":true}
{"value_id":"4-37-1-0","node_id":4,"class_id":37,"type":"bool","genre":"user","instance":1,"index":0,"label":"Switch","units":"","help":"","read_only":false,"write_only":false,"min":0,"max":0,"is_polled":false,"value":false}
{"value_id":"4-37-1-0","node_id":4,"class_id":37,"type":"bool","genre":"user","instance":1,"index":0,"label":"Switch","units":"","help":"","read_only":false,"write_only":false,"min":0,"max":0,"is_polled":false,"value":false}

Turning on the switch

{"value_id":"4-37-1-0","node_id":4,"class_id":37,"type":"bool","genre":"user","instance":1,"index":0,"label":"Switch","units":"","help":"","read_only":false,"write_only":false,"min":0,"max":0,"is_polled":false,"value":false}
{"value_id":"4-37-1-0","node_id":4,"class_id":37,"type":"bool","genre":"user","instance":1,"index":0,"label":"Switch","units":"","help":"","read_only":false,"write_only":false,"min":0,"max":0,"is_polled":false,"value":false}
{"value_id":"4-37-1-0","node_id":4,"class_id":37,"type":"bool","genre":"user","instance":1,"index":0,"label":"Switch","units":"","help":"","read_only":false,"write_only":false,"min":0,"max":0,"is_polled":false,"value":true}
{"value_id":"4-37-1-0","node_id":4,"class_id":37,"type":"bool","genre":"user","instance":1,"index":0,"label":"Switch","units":"","help":"","read_only":false,"write_only":false,"min":0,"max":0,"is_polled":false,"value":true}
sjorge picture sjorge on 14 Mar 2019

Here is a OZW log from startup to toglging the value on and off and then stopping zwave2mqtt

2019-03-16 16:00:13.264 Always, OpenZwave Version 1.4.3405 Starting Up
2019-03-16 16:00:13.264 Info, Setting Up Provided Network Key for Secure Communications
2019-03-16 16:00:13.265 Info, mgr,     Added driver for controller /dev/cua/0
2019-03-16 16:00:13.265 Info,   Opening controller /dev/cua/0
2019-03-16 16:00:13.265 Info, Trying to open serial port /dev/cua/0 (attempt 1)
2019-03-16 16:00:13.267 Info, Serial port /dev/cua/0 opened (attempt 1)
2019-03-16 16:00:13.268 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-03-16 16:00:13.268 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-03-16 16:00:13.268 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-03-16 16:00:13.268 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-03-16 16:00:13.268 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-03-16 16:00:13.268 Detail,
2019-03-16 16:00:13.268 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-03-16 16:00:13.271 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x36, 0x2e, 0x30, 0x32, 0x00, 0x01, 0x92
2019-03-16 16:00:13.271 Detail,
2019-03-16 16:00:13.271 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2019-03-16 16:00:13.271 Info, contrlr,     Static Controller library, version Z-Wave 6.02
2019-03-16 16:00:13.271 Detail, Node045,   Expected reply was received
2019-03-16 16:00:13.271 Detail, Node045,   Message transaction complete
2019-03-16 16:00:13.271 Detail,
2019-03-16 16:00:13.271 Detail, contrlr, Removing current message
2019-03-16 16:00:13.271 Detail,
2019-03-16 16:00:13.271 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-03-16 16:00:13.274 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xfc, 0x20, 0xd0, 0x90, 0x01, 0x4b
2019-03-16 16:00:13.274 Detail,
2019-03-16 16:00:13.274 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xfc20d090.  Our node ID = 1
2019-03-16 16:00:13.274 Detail, Node032,   Expected reply was received
2019-03-16 16:00:13.274 Detail, Node032,   Message transaction complete
2019-03-16 16:00:13.274 Detail,
2019-03-16 16:00:13.274 Detail, contrlr, Removing current message
2019-03-16 16:00:13.274 Detail,
2019-03-16 16:00:13.274 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-03-16 16:00:13.276 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x1c, 0xe3
2019-03-16 16:00:13.276 Detail,
2019-03-16 16:00:13.276 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2019-03-16 16:00:13.276 Info, contrlr,     There is a SUC ID Server (SIS) in this network.
2019-03-16 16:00:13.276 Info, contrlr,     The PC controller is an inclusion static update controller (SUC) and was the original primary before the SIS was added.
2019-03-16 16:00:13.277 Detail, Node227,   Expected reply was received
2019-03-16 16:00:13.277 Detail, Node227,   Message transaction complete
2019-03-16 16:00:13.277 Detail,
2019-03-16 16:00:13.277 Detail, contrlr, Removing current message
2019-03-16 16:00:13.277 Detail,
2019-03-16 16:00:13.277 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-03-16 16:00:13.281 Detail, contrlr,   Received: 0x01, 0x2b, 0x01, 0x07, 0x05, 0x24, 0x01, 0x15, 0x04, 0x00, 0x00, 0x01, 0xfe, 0x87, 0x7f, 0x88, 0xcf, 0x3f, 0xc0, 0x47, 0xfb, 0xdf, 0xfd, 0xe0, 0x67, 0x00, 0x80, 0x80, 0x00, 0x80, 0x86, 0x00, 0x00, 0x00, 0xe8, 0x73, 0x00, 0x80, 0x0f, 0x00, 0x00, 0x60, 0x5a, 0x00, 0x6d
2019-03-16 16:00:13.281 Detail,
2019-03-16 16:00:13.281 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2019-03-16 16:00:13.281 Info, contrlr,     Serial API Version:   5.36
2019-03-16 16:00:13.281 Info, contrlr,     Manufacturer ID:      0x0115
2019-03-16 16:00:13.281 Info, contrlr,     Product Type:         0x0400
2019-03-16 16:00:13.281 Info, contrlr,     Product ID:           0x0001
2019-03-16 16:00:13.281 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-03-16 16:00:13.281 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-03-16 16:00:13.281 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2019-03-16 16:00:13.281 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2019-03-16 16:00:13.281 Detail, Node036,   Expected reply was received
2019-03-16 16:00:13.281 Detail, Node036,   Message transaction complete
2019-03-16 16:00:13.282 Detail,
2019-03-16 16:00:13.282 Detail, contrlr, Removing current message
2019-03-16 16:00:13.282 Detail,
2019-03-16 16:00:13.282 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-03-16 16:00:13.283 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x01, 0xad
2019-03-16 16:00:13.283 Detail,
2019-03-16 16:00:13.283 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 1
2019-03-16 16:00:13.283 Detail, Node173,   Expected reply was received
2019-03-16 16:00:13.283 Detail, Node173,   Message transaction complete
2019-03-16 16:00:13.283 Detail,
2019-03-16 16:00:13.284 Detail, contrlr, Removing current message
2019-03-16 16:00:13.284 Detail,
2019-03-16 16:00:13.284 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-03-16 16:00:13.341 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0xaa, 0x3a, 0xb5, 0xd8, 0xdf, 0x3c, 0x2c, 0xf1, 0x1c, 0x73, 0x8c, 0xfa, 0x6d, 0xf3, 0x8d, 0x4d, 0x5d, 0x4b, 0x41, 0x90, 0xbf, 0x82, 0x1b, 0x77, 0xa6, 0xce, 0x41, 0x24, 0xd4, 0x74, 0x0e, 0xc3, 0x94
2019-03-16 16:00:13.341 Detail,
2019-03-16 16:00:13.341 Info, contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2019-03-16 16:00:13.341 Detail, Node032,   Expected reply was received
2019-03-16 16:00:13.341 Detail, Node032,   Message transaction complete
2019-03-16 16:00:13.341 Detail,
2019-03-16 16:00:13.341 Detail, contrlr, Removing current message
2019-03-16 16:00:13.341 Detail,
2019-03-16 16:00:13.341 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-03-16 16:00:13.439 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x02, 0x06, 0x08, 0x1d, 0x0b, 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, 0x05, 0x00, 0xc4
2019-03-16 16:00:13.439 Detail,
2019-03-16 16:00:13.439 Info, mgr,     Driver with Home ID of 0xfc20d090 is now ready.
2019-03-16 16:00:13.439 Info,
2019-03-16 16:00:13.451 Detail, Node004, Queuing (Controller) Delete All Return Routes
2019-03-16 16:00:13.451 Detail, Node004, Queuing (Controller) Assign Return Route
2019-03-16 16:00:13.452 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2019-03-16 16:00:13.452 Info, contrlr,     Node 001 - Known
2019-03-16 16:00:13.452 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2019-03-16 16:00:13.452 Detail, Node001, QueryStage_CacheLoad
2019-03-16 16:00:13.452 Info, Node001, Node Identity Codes: 0115:0400:0001
2019-03-16 16:00:13.452 Detail, Node001, QueryStage_Associations
2019-03-16 16:00:13.452 Detail, Node001, QueryStage_Neighbors
2019-03-16 16:00:13.452 Detail, contrlr, Requesting routing info (neighbor list) for Node 1
2019-03-16 16:00:13.452 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2019-03-16 16:00:13.452 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2019-03-16 16:00:13.452 Info, contrlr,     Node 002 - Known
2019-03-16 16:00:13.452 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2019-03-16 16:00:13.452 Detail, Node002, QueryStage_CacheLoad
2019-03-16 16:00:13.452 Info, Node002, Node Identity Codes: 0086:0002:0064
2019-03-16 16:00:13.452 Info, Node002, NoOperation::Set - Routing=true
2019-03-16 16:00:13.453 Detail, Node002, Queuing (NoOp) NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xca
2019-03-16 16:00:13.453 Detail, Node002, Queuing (Query) Query Stage Complete (CacheLoad)
2019-03-16 16:00:13.453 Info, contrlr,     Node 004 - Known
2019-03-16 16:00:13.453 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2019-03-16 16:00:13.453 Detail, Node004, QueryStage_CacheLoad
2019-03-16 16:00:13.453 Info, Node004, Node Identity Codes: 0086:0003:0074
2019-03-16 16:00:13.453 Info, Node004, NoOperation::Set - Routing=true
2019-03-16 16:00:13.453 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xcd
2019-03-16 16:00:13.453 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2019-03-16 16:00:13.453 Detail, Node008,   Expected reply was received
2019-03-16 16:00:13.453 Detail, Node008,   Message transaction complete
2019-03-16 16:00:13.453 Detail,
2019-03-16 16:00:13.453 Detail, contrlr, Removing current message
2019-03-16 16:00:13.453 Detail, Node001, Notification: DriverReady
2019-03-16 16:00:13.453 Detail, Node001, Notification: NodeAdded
2019-03-16 16:00:13.453 Detail, Node001, Notification: NodeProtocolInfo
2019-03-16 16:00:13.453 Detail, Node001, Notification: EssentialNodeQueriesComplete
2019-03-16 16:00:13.453 Detail, Node001, Notification: ValueAdded
2019-03-16 16:00:13.454 Detail, Node001, Notification: NodeNaming
2019-03-16 16:00:13.454 Detail, Node002, Notification: NodeAdded
2019-03-16 16:00:13.454 Detail, Node002, Notification: NodeProtocolInfo
2019-03-16 16:00:13.463 Detail, Node002, Notification: EssentialNodeQueriesComplete
2019-03-16 16:00:13.463 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.463 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.466 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.472 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.472 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.472 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.474 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.475 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.475 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.483 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.483 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.483 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.483 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.483 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.483 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.483 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.483 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.484 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: Group
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node002, Notification: NodeNaming
2019-03-16 16:00:13.498 Detail, Node004, Notification: NodeAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: NodeProtocolInfo
2019-03-16 16:00:13.498 Detail, Node004, Notification: EssentialNodeQueriesComplete
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.498 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.499 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.512 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.513 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.513 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.513 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.513 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.513 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.513 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.513 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.513 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.523 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.523 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.523 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.523 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.524 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.532 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.532 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.532 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.532 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.532 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.532 Detail, Node004, Notification: Group
2019-03-16 16:00:13.532 Detail, Node004, Notification: Group
2019-03-16 16:00:13.532 Detail, Node004, Notification: Group
2019-03-16 16:00:13.532 Detail, Node004, Notification: Group
2019-03-16 16:00:13.532 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.532 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.532 Detail, Node004, Notification: ValueAdded
2019-03-16 16:00:13.532 Detail, Node004, Notification: NodeNaming
2019-03-16 16:00:13.532 Detail,
2019-03-16 16:00:13.532 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2019-03-16 16:00:13.534 Detail, contrlr,   Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
2019-03-16 16:00:13.534 Detail,
2019-03-16 16:00:13.534 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2019-03-16 16:00:13.534 Detail, Node015,   Expected reply was received
2019-03-16 16:00:13.534 Detail, Node015,   Message transaction complete
2019-03-16 16:00:13.534 Detail,
2019-03-16 16:00:13.534 Detail, contrlr, Removing current message
2019-03-16 16:00:13.534 Detail,
2019-03-16 16:00:13.534 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2019-03-16 16:00:13.536 Detail, contrlr, Removing current message
2019-03-16 16:00:13.536 Detail,
2019-03-16 16:00:13.536 Info, Node001, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2019-03-16 16:00:13.545 Detail, Node001,   Received: 0x01, 0x20, 0x01, 0x80, 0x0a, 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, 0x54
2019-03-16 16:00:13.545 Detail,
2019-03-16 16:00:13.545 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2019-03-16 16:00:13.545 Info, Node001,     Neighbors of this node are:
2019-03-16 16:00:13.545 Info, Node001,     Node 2
2019-03-16 16:00:13.545 Info, Node001,     Node 4
2019-03-16 16:00:13.545 Detail,   Expected reply was received
2019-03-16 16:00:13.545 Detail,   Message transaction complete
2019-03-16 16:00:13.545 Detail,
2019-03-16 16:00:13.545 Detail, Node001, Removing current message
2019-03-16 16:00:13.545 Detail,
2019-03-16 16:00:13.545 Info, Node002, Sending (NoOp) message (Callback ID=0x0a, Expected Reply=0x13) - NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xca
2019-03-16 16:00:13.556 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:13.556 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:16.094 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x0a, 0x01, 0x01, 0xba, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x39
2019-03-16 16:00:16.095 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a)
2019-03-16 16:00:16.095 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-03-16 16:00:16.095 Warning, Node002, WARNING: Device is not a sleeping node.
2019-03-16 16:00:16.095 Info, Node002, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 0 pending 1
2019-03-16 16:00:16.095 Detail, Node001,   Expected callbackId was received
2019-03-16 16:00:16.095 Detail, Node001,   Expected reply was received
2019-03-16 16:00:16.095 Detail, Node001,   Message transaction complete
2019-03-16 16:00:16.095 Detail,
2019-03-16 16:00:16.095 Detail, Node002, Removing current message
2019-03-16 16:00:16.095 Detail, Node002, Notification: Notification - NoOperation
2019-03-16 16:00:16.095 Detail,
2019-03-16 16:00:16.095 Info, Node004, Sending (NoOp) message (Callback ID=0x0b, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xcd
2019-03-16 16:00:16.102 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:16.103 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:16.122 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x0b, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x3f
2019-03-16 16:00:16.122 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b)
2019-03-16 16:00:16.122 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:16.122 Detail,   Expected callbackId was received
2019-03-16 16:00:16.122 Detail,   Expected reply was received
2019-03-16 16:00:16.123 Detail,   Message transaction complete
2019-03-16 16:00:16.123 Detail,
2019-03-16 16:00:16.123 Detail, Node004, Removing current message
2019-03-16 16:00:16.123 Detail, Node004, Notification: Notification - NoOperation
2019-03-16 16:00:16.123 Info, Deleting all return routes from node 4
2019-03-16 16:00:16.123 Detail, Node004, Queuing (Command) ControllerCommand_DeleteAllReturnRoutess (Node=4): 0x01, 0x05, 0x00, 0x47, 0x04, 0x0c, 0xb5
2019-03-16 16:00:16.123 Detail, Notification: ControllerCommand - Starting
2019-03-16 16:00:16.123 Detail,
2019-03-16 16:00:16.123 Info, Node004, Sending (Command) message (Callback ID=0x0c, Expected Reply=0x47) - ControllerCommand_DeleteAllReturnRoutess (Node=4): 0x01, 0x05, 0x00, 0x47, 0x04, 0x0c, 0xb5
2019-03-16 16:00:16.130 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x47, 0x01, 0xbc
2019-03-16 16:00:16.130 Detail,
2019-03-16 16:00:16.130 Info, Node004, Received reply to FUNC_ID_ZW_DELETE_RETURN_ROUTE - command in progress
2019-03-16 16:00:16.130 Detail, Notification: ControllerCommand - InProgress
2019-03-16 16:00:16.242 Detail, Node004,   Received: 0x01, 0x05, 0x00, 0x47, 0x0c, 0x00, 0xb1
2019-03-16 16:00:16.242 Detail,
2019-03-16 16:00:16.242 Info, Node004, Received reply to FUNC_ID_ZW_DELETE_RETURN_ROUTE for node 4 - SUCCESS
2019-03-16 16:00:16.242 Detail,   Expected callbackId was received
2019-03-16 16:00:16.242 Detail,   Expected reply was received
2019-03-16 16:00:16.242 Detail,   Message transaction complete
2019-03-16 16:00:16.242 Detail,
2019-03-16 16:00:16.242 Detail, Node004, Removing current message
2019-03-16 16:00:16.242 Detail, Notification: ControllerCommand - Completed
2019-03-16 16:00:16.242 Info, Assigning return route from node 4 to node 1
2019-03-16 16:00:16.243 Detail, Node004, Queuing (Command) ControllerCommand_AssignReturnRoute (Node=4): 0x01, 0x06, 0x00, 0x46, 0x04, 0x01, 0x0d, 0xb7
2019-03-16 16:00:16.243 Detail, Notification: ControllerCommand - Starting
2019-03-16 16:00:16.243 Detail,
2019-03-16 16:00:16.243 Info, Node004, Sending (Command) message (Callback ID=0x0d, Expected Reply=0x46) - ControllerCommand_AssignReturnRoute (Node=4): 0x01, 0x06, 0x00, 0x46, 0x04, 0x01, 0x0d, 0xb7
2019-03-16 16:00:16.255 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x46, 0x01, 0xbd
2019-03-16 16:00:16.255 Detail,
2019-03-16 16:00:16.255 Info, Node004, Received reply to FUNC_ID_ZW_ASSIGN_RETURN_ROUTE - command in progress
2019-03-16 16:00:16.255 Detail, Notification: ControllerCommand - InProgress
2019-03-16 16:00:16.544 Detail, Node004,   Received: 0x01, 0x05, 0x00, 0x46, 0x0d, 0x00, 0xb1
2019-03-16 16:00:16.544 Detail,
2019-03-16 16:00:16.544 Info, Node004, Received reply to FUNC_ID_ZW_ASSIGN_RETURN_ROUTE for node 4 - SUCCESS
2019-03-16 16:00:16.544 Detail,   Expected callbackId was received
2019-03-16 16:00:16.544 Detail,   Expected reply was received
2019-03-16 16:00:16.544 Detail,   Message transaction complete
2019-03-16 16:00:16.544 Detail,
2019-03-16 16:00:16.544 Detail, Node004, Removing current message
2019-03-16 16:00:16.544 Detail, Notification: ControllerCommand - Completed
2019-03-16 16:00:16.544 Detail, Node001, Query Stage Complete (Neighbors)
2019-03-16 16:00:16.544 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2019-03-16 16:00:16.544 Detail, Node001, QueryStage_Session
2019-03-16 16:00:16.544 Detail, Node001, QueryStage_Dynamic
2019-03-16 16:00:16.544 Detail, Node001, QueryStage_Configuration
2019-03-16 16:00:16.544 Detail, Node001, QueryStage_Complete
2019-03-16 16:00:16.544 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2019-03-16 16:00:16.544 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0
2019-03-16 16:00:16.544 Detail, Node001, Notification: NodeQueriesComplete
2019-03-16 16:00:16.545 Detail, Node002, Query Stage Complete (CacheLoad)
2019-03-16 16:00:16.545 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=1 queryStage=CacheLoad live=1
2019-03-16 16:00:16.545 Detail, Node002, QueryStage_CacheLoad
2019-03-16 16:00:16.545 Info, Node002, Node Identity Codes: 0086:0002:0064
2019-03-16 16:00:16.545 Info, Node002, NoOperation::Set - Routing=true
2019-03-16 16:00:16.545 Detail, Node002, Queuing (NoOp) NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x0e, 0xce
2019-03-16 16:00:16.545 Detail, Node002, Queuing (Query) Query Stage Complete (CacheLoad)
2019-03-16 16:00:16.545 Detail,
2019-03-16 16:00:16.545 Info, Node002, Sending (NoOp) message (Callback ID=0x0e, Expected Reply=0x13) - NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x0e, 0xce
2019-03-16 16:00:16.556 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:16.556 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:18.047 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x0e, 0x01, 0x01, 0xc2, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x44
2019-03-16 16:00:18.047 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0e received (expected 0x0e)
2019-03-16 16:00:18.047 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-03-16 16:00:18.047 Warning, Node002, WARNING: Device is not a sleeping node.
2019-03-16 16:00:18.047 Info, Node002, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 1 pending 1
2019-03-16 16:00:18.047 Detail, Node001,   Expected callbackId was received
2019-03-16 16:00:18.047 Detail, Node001,   Expected reply was received
2019-03-16 16:00:18.047 Detail, Node001,   Message transaction complete
2019-03-16 16:00:18.047 Detail,
2019-03-16 16:00:18.048 Detail, Node002, Removing current message
2019-03-16 16:00:18.048 Detail, Node002, Notification: Notification - NoOperation
2019-03-16 16:00:18.048 Detail, Node004, Query Stage Complete (CacheLoad)
2019-03-16 16:00:18.048 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
2019-03-16 16:00:18.048 Detail, Node004, QueryStage_Associations
2019-03-16 16:00:18.048 Info, Node004, Number of association groups reported for node 4 is 4.
2019-03-16 16:00:18.048 Info, Node004, Get Associations for group 1 of node 4
2019-03-16 16:00:18.048 Detail, Node004, Queuing (Send) AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x01, 0x25, 0x0f, 0x4d
2019-03-16 16:00:18.048 Detail, Node004, Queuing (Query) Query Stage Complete (Associations)
2019-03-16 16:00:18.048 Detail,
2019-03-16 16:00:18.048 Info, Node004, Sending (Send) message (Callback ID=0x0f, Expected Reply=0x04) - AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x01, 0x25, 0x0f, 0x4d
2019-03-16 16:00:18.056 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:18.056 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:18.076 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x0f, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x3b
2019-03-16 16:00:18.076 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0f received (expected 0x0f)
2019-03-16 16:00:18.076 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:18.076 Detail,   Expected callbackId was received
2019-03-16 16:00:18.090 Detail, Node004,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x04, 0x06, 0x85, 0x03, 0x01, 0x05, 0x00, 0x01, 0xc3, 0x00, 0xb7
2019-03-16 16:00:18.091 Detail,
2019-03-16 16:00:18.091 Info, Node004, Response RTT 42 Average Response RTT 42
2019-03-16 16:00:18.091 Info, Node004, Received Association report from node 4, group 1, containing 1 associations
2019-03-16 16:00:18.091 Info, Node004,   The group contains:
2019-03-16 16:00:18.091 Info, Node004,     Node 1
2019-03-16 16:00:18.091 Info, Node004, Get Associations for group 2 of node 4
2019-03-16 16:00:18.091 Detail, Node004, Queuing (Send) AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x02, 0x25, 0x10, 0x51
2019-03-16 16:00:18.091 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:18.091 Detail, Node004,   Message transaction complete
2019-03-16 16:00:18.091 Detail,
2019-03-16 16:00:18.091 Detail, Node004, Removing current message
2019-03-16 16:00:18.091 Detail,
2019-03-16 16:00:18.091 Info, Node004, Sending (Send) message (Callback ID=0x10, Expected Reply=0x04) - AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x02, 0x25, 0x10, 0x51
2019-03-16 16:00:18.099 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:18.099 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:18.118 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x10, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x24
2019-03-16 16:00:18.118 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x10 received (expected 0x10)
2019-03-16 16:00:18.118 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:18.119 Detail,   Expected callbackId was received
2019-03-16 16:00:18.131 Detail, Node004,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x04, 0x05, 0x85, 0x03, 0x02, 0x05, 0x00, 0xc3, 0x00, 0xb5
2019-03-16 16:00:18.131 Detail,
2019-03-16 16:00:18.131 Info, Node004, Response RTT 39 Average Response RTT 40
2019-03-16 16:00:18.131 Info, Node004, Received Association report from node 4, group 2, containing 0 associations
2019-03-16 16:00:18.131 Info, Node004, Get Associations for group 3 of node 4
2019-03-16 16:00:18.131 Detail, Node004, Queuing (Send) AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x03, 0x25, 0x11, 0x51
2019-03-16 16:00:18.132 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:18.132 Detail, Node004,   Message transaction complete
2019-03-16 16:00:18.132 Detail,
2019-03-16 16:00:18.132 Detail, Node004, Removing current message
2019-03-16 16:00:18.132 Detail, Node004, Notification: Group
2019-03-16 16:00:18.132 Detail,
2019-03-16 16:00:18.132 Info, Node004, Sending (Send) message (Callback ID=0x11, Expected Reply=0x04) - AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x03, 0x25, 0x11, 0x51
2019-03-16 16:00:18.139 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:18.139 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:18.159 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x11, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x25
2019-03-16 16:00:18.159 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x11 received (expected 0x11)
2019-03-16 16:00:18.159 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:18.159 Detail,   Expected callbackId was received
2019-03-16 16:00:18.172 Detail, Node004,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x04, 0x05, 0x85, 0x03, 0x03, 0x05, 0x00, 0xc3, 0x00, 0xb4
2019-03-16 16:00:18.172 Detail,
2019-03-16 16:00:18.172 Info, Node004, Response RTT 39 Average Response RTT 39
2019-03-16 16:00:18.172 Info, Node004, Received Association report from node 4, group 3, containing 0 associations
2019-03-16 16:00:18.172 Info, Node004, Get Associations for group 4 of node 4
2019-03-16 16:00:18.172 Detail, Node004, Queuing (Send) AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x04, 0x25, 0x12, 0x55
2019-03-16 16:00:18.172 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:18.172 Detail, Node004,   Message transaction complete
2019-03-16 16:00:18.172 Detail,
2019-03-16 16:00:18.172 Detail, Node004, Removing current message
2019-03-16 16:00:18.172 Detail, Node004, Notification: Group
2019-03-16 16:00:18.172 Detail,
2019-03-16 16:00:18.172 Info, Node004, Sending (Send) message (Callback ID=0x12, Expected Reply=0x04) - AssociationCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x85, 0x02, 0x04, 0x25, 0x12, 0x55
2019-03-16 16:00:18.180 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:18.180 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:18.200 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x12, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x26
2019-03-16 16:00:18.200 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x12 received (expected 0x12)
2019-03-16 16:00:18.200 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:18.200 Detail,   Expected callbackId was received
2019-03-16 16:00:18.212 Detail, Node004,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x04, 0x05, 0x85, 0x03, 0x04, 0x05, 0x00, 0xc3, 0x00, 0xb3
2019-03-16 16:00:18.212 Detail,
2019-03-16 16:00:18.212 Info, Node004, Response RTT 40 Average Response RTT 39
2019-03-16 16:00:18.212 Info, Node004, Received Association report from node 4, group 4, containing 0 associations
2019-03-16 16:00:18.213 Info, Node004, Querying associations for node 4 is complete.
2019-03-16 16:00:18.213 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:18.213 Detail, Node004,   Message transaction complete
2019-03-16 16:00:18.213 Detail,
2019-03-16 16:00:18.213 Detail, Node004, Removing current message
2019-03-16 16:00:18.213 Detail, Node004, Notification: Group
2019-03-16 16:00:18.213 Detail, Node002, Query Stage Complete (CacheLoad)
2019-03-16 16:00:18.213 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=2 queryStage=CacheLoad live=1
2019-03-16 16:00:18.213 Detail, Node002, QueryStage_CacheLoad
2019-03-16 16:00:18.213 Info, Node002, Node Identity Codes: 0086:0002:0064
2019-03-16 16:00:18.213 Info, Node002, NoOperation::Set - Routing=true
2019-03-16 16:00:18.213 Detail, Node002, Queuing (NoOp) NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x13, 0xd3
2019-03-16 16:00:18.213 Detail, Node002, Queuing (Query) Query Stage Complete (CacheLoad)
2019-03-16 16:00:18.213 Detail,
2019-03-16 16:00:18.213 Info, Node002, Sending (NoOp) message (Callback ID=0x13, Expected Reply=0x13) - NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x13, 0xd3
2019-03-16 16:00:18.224 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:18.224 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:20.973 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x13, 0x01, 0x01, 0xba, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x21
2019-03-16 16:00:20.973 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x13 received (expected 0x13)
2019-03-16 16:00:20.973 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-03-16 16:00:20.973 Warning, Node002, WARNING: Device is not a sleeping node.
2019-03-16 16:00:20.973 Error, Node002, ERROR: node presumed dead
2019-03-16 16:00:20.973 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2019-03-16 16:00:20.973 Warning, CheckCompletedNodeQueries all=0, deadFound=1 sleepingOnly=0
2019-03-16 16:00:20.973 Info, Node002, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 2 pending 1
2019-03-16 16:00:20.973 Detail, Node001,   Expected callbackId was received
2019-03-16 16:00:20.973 Detail, Node001,   Expected reply was received
2019-03-16 16:00:20.973 Detail, Node001,   Message transaction complete
2019-03-16 16:00:20.973 Detail,
2019-03-16 16:00:20.973 Detail, Node002, Removing current message
2019-03-16 16:00:20.974 Detail, Node002, Notification: Notification - NoOperation
2019-03-16 16:00:20.974 Detail, Node002, Notification: Notification - Node Dead
2019-03-16 16:00:20.974 Detail, Node004, Query Stage Complete (Associations)
2019-03-16 16:00:20.974 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Neighbors live=1
2019-03-16 16:00:20.974 Detail, Node004, QueryStage_Neighbors
2019-03-16 16:00:20.974 Detail, Requesting routing info (neighbor list) for Node 4
2019-03-16 16:00:20.974 Detail, Node004, Queuing (Command) Get Routing Info (Node=4): 0x01, 0x07, 0x00, 0x80, 0x04, 0x00, 0x00, 0x03, 0x7f
2019-03-16 16:00:20.974 Detail, Node004, Queuing (Query) Query Stage Complete (Neighbors)
2019-03-16 16:00:20.974 Detail,
2019-03-16 16:00:20.974 Info, Node004, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=4): 0x01, 0x07, 0x00, 0x80, 0x04, 0x00, 0x00, 0x03, 0x7f
2019-03-16 16:00:20.978 Detail, Node004,   Received: 0x01, 0x20, 0x01, 0x80, 0x01, 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, 0x5f
2019-03-16 16:00:20.979 Detail,
2019-03-16 16:00:20.979 Info, Node004, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2019-03-16 16:00:20.979 Info, Node004,     Neighbors of this node are:
2019-03-16 16:00:20.979 Info, Node004,     Node 1
2019-03-16 16:00:20.979 Detail,   Expected reply was received
2019-03-16 16:00:20.979 Detail,   Message transaction complete
2019-03-16 16:00:20.979 Detail,
2019-03-16 16:00:20.979 Detail, Node004, Removing current message
2019-03-16 16:00:20.979 Detail, Node002, Query Stage Complete (CacheLoad)
2019-03-16 16:00:20.979 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=0
2019-03-16 16:00:20.979 Detail, Node004, Query Stage Complete (Neighbors)
2019-03-16 16:00:20.979 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2019-03-16 16:00:20.979 Detail, Node004, QueryStage_Session
2019-03-16 16:00:20.979 Detail, Node004, Queuing (Query) SwitchAllCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x27, 0x02, 0x25, 0x14, 0xf7
2019-03-16 16:00:20.979 Detail, Node004, Queuing (Query) PowerlevelCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x73, 0x02, 0x25, 0x15, 0xa2
2019-03-16 16:00:20.979 Detail, Node004, Queuing (Query) Query Stage Complete (Session)
2019-03-16 16:00:20.980 Detail,
2019-03-16 16:00:20.980 Info, Node004, Sending (Query) message (Callback ID=0x14, Expected Reply=0x04) - SwitchAllCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x27, 0x02, 0x25, 0x14, 0xf7
2019-03-16 16:00:20.987 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:20.987 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.007 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x14, 0x00, 0x00, 0x03, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x21
2019-03-16 16:00:21.007 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x14 received (expected 0x14)
2019-03-16 16:00:21.007 Info, Node004, Request RTT 28 Average Request RTT 27
2019-03-16 16:00:21.007 Detail,   Expected callbackId was received
2019-03-16 16:00:21.020 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x27, 0x03, 0x00, 0xc3, 0x00, 0x10
2019-03-16 16:00:21.020 Detail,
2019-03-16 16:00:21.020 Info, Node004, Response RTT 41 Average Response RTT 40
2019-03-16 16:00:21.020 Detail, Node004, Initial read of value
2019-03-16 16:00:21.020 Info, Node004, Received SwitchAll report from node 4: Disabled
2019-03-16 16:00:21.020 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.020 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.021 Detail,
2019-03-16 16:00:21.021 Detail, Node004, Removing current message
2019-03-16 16:00:21.021 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.021 Detail,
2019-03-16 16:00:21.021 Info, Node004, Sending (Query) message (Callback ID=0x15, Expected Reply=0x04) - PowerlevelCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x73, 0x02, 0x25, 0x15, 0xa2
2019-03-16 16:00:21.028 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:21.028 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.048 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x15, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x21
2019-03-16 16:00:21.048 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x15 received (expected 0x15)
2019-03-16 16:00:21.048 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:21.048 Detail,   Expected callbackId was received
2019-03-16 16:00:21.060 Detail, Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x04, 0x73, 0x03, 0x00, 0x00, 0xc3, 0x00, 0x44
2019-03-16 16:00:21.060 Detail,
2019-03-16 16:00:21.060 Info, Node004, Response RTT 39 Average Response RTT 39
2019-03-16 16:00:21.061 Info, Node004, Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2019-03-16 16:00:21.061 Detail, Node004, Initial read of value
2019-03-16 16:00:21.061 Detail, Node004, Initial read of value
2019-03-16 16:00:21.061 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.061 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.061 Detail,
2019-03-16 16:00:21.061 Detail, Node004, Removing current message
2019-03-16 16:00:21.061 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.061 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.061 Detail, Node004, Query Stage Complete (Session)
2019-03-16 16:00:21.061 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=1
2019-03-16 16:00:21.061 Detail, Node004, QueryStage_Dynamic
2019-03-16 16:00:21.061 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x16, 0xf7
2019-03-16 16:00:21.061 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x00, 0x25, 0x17, 0xe0
2019-03-16 16:00:21.061 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x10, 0x25, 0x18, 0xff
2019-03-16 16:00:21.061 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x20, 0x25, 0x19, 0xce
2019-03-16 16:00:21.061 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x28, 0x25, 0x1a, 0xc5
2019-03-16 16:00:21.062 Detail, Node004, Queuing (Send) AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x04, 0x01, 0x25, 0x1b, 0xaf
2019-03-16 16:00:21.062 Detail, Node004, Queuing (Send) AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x08, 0x01, 0x25, 0x1c, 0xa4
2019-03-16 16:00:21.062 Detail, Node004, Queuing (Send) ClockCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x81, 0x05, 0x25, 0x1d, 0x5f
2019-03-16 16:00:21.062 Detail, Node004, Queuing (Query) Query Stage Complete (Dynamic)
2019-03-16 16:00:21.062 Detail,
2019-03-16 16:00:21.062 Info, Node004, Sending (Send) message (Callback ID=0x16, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x16, 0xf7
2019-03-16 16:00:21.069 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:21.070 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.089 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x16, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x22
2019-03-16 16:00:21.089 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x16 received (expected 0x16)
2019-03-16 16:00:21.089 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:21.089 Detail,   Expected callbackId was received
2019-03-16 16:00:21.101 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0xff, 0xc3, 0x00, 0xed
2019-03-16 16:00:21.101 Detail,
2019-03-16 16:00:21.101 Info, Node004, Response RTT 39 Average Response RTT 39
2019-03-16 16:00:21.102 Info, Node004, Received SwitchBinary report from node 4: level=On
2019-03-16 16:00:21.102 Detail, Node004, Initial read of value
2019-03-16 16:00:21.102 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.102 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.102 Detail,
2019-03-16 16:00:21.102 Detail, Node004, Removing current message
2019-03-16 16:00:21.102 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.102 Detail,
2019-03-16 16:00:21.102 Info, Node004, Sending (Send) message (Callback ID=0x17, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x00, 0x25, 0x17, 0xe0
2019-03-16 16:00:21.109 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:21.110 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.129 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x17, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x23
2019-03-16 16:00:21.129 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x17 received (expected 0x17)
2019-03-16 16:00:21.130 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:21.130 Detail,   Expected callbackId was received
2019-03-16 16:00:21.144 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x00, 0x27, 0x0c, 0x00, 0x11, 0x00, 0x00, 0x27, 0x0c, 0xc3, 0x00, 0x40
2019-03-16 16:00:21.144 Detail,
2019-03-16 16:00:21.144 Info, Node004, Response RTT 41 Average Response RTT 40
2019-03-16 16:00:21.144 Detail, Node004, Initial read of value
2019-03-16 16:00:21.144 Info, Node004, Received Meter report from node 4: Energy=9.996kWh
2019-03-16 16:00:21.144 Detail, Node004, Initial read of value
2019-03-16 16:00:21.144 Info, Node004,     Previous value was 9.996kWh, received 17 seconds ago.
2019-03-16 16:00:21.144 Detail, Node004, Initial read of value
2019-03-16 16:00:21.144 Detail, Node004, Initial read of value
2019-03-16 16:00:21.144 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.145 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.145 Detail,
2019-03-16 16:00:21.145 Detail, Node004, Removing current message
2019-03-16 16:00:21.145 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.145 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.145 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.145 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.145 Detail,
2019-03-16 16:00:21.145 Info, Node004, Sending (Send) message (Callback ID=0x18, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x10, 0x25, 0x18, 0xff
2019-03-16 16:00:21.153 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:21.153 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.173 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x18, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x2c
2019-03-16 16:00:21.173 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x18 received (expected 0x18)
2019-03-16 16:00:21.173 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:21.173 Detail,   Expected callbackId was received
2019-03-16 16:00:21.190 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x7e, 0x61, 0x00, 0x00, 0xc3, 0x00, 0x5e
2019-03-16 16:00:21.190 Detail,
2019-03-16 16:00:21.190 Info, Node004, Response RTT 44 Average Response RTT 42
2019-03-16 16:00:21.190 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:21.190 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:21.190 Info, Node004, Received Meter report from node 4: Power=32.353W
2019-03-16 16:00:21.190 Detail, Node004, Initial read of value
2019-03-16 16:00:21.190 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.190 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.191 Detail,
2019-03-16 16:00:21.191 Detail, Node004, Removing current message
2019-03-16 16:00:21.191 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.191 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.191 Detail,
2019-03-16 16:00:21.191 Info, Node004, Sending (Send) message (Callback ID=0x19, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x20, 0x25, 0x19, 0xce
2019-03-16 16:00:21.198 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:21.198 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.218 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x19, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x2d
2019-03-16 16:00:21.218 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x19 received (expected 0x19)
2019-03-16 16:00:21.218 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:21.218 Detail,   Expected callbackId was received
2019-03-16 16:00:21.232 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x64, 0x00, 0x03, 0x80, 0x76, 0x00, 0x00, 0xc3, 0x00, 0x24
2019-03-16 16:00:21.232 Detail,
2019-03-16 16:00:21.232 Info, Node004, Response RTT 40 Average Response RTT 41
2019-03-16 16:00:21.232 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:21.232 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:21.232 Info, Node004, Received Meter report from node 4: Voltage=229.494V
2019-03-16 16:00:21.232 Detail, Node004, Initial read of value
2019-03-16 16:00:21.232 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.232 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.232 Detail,
2019-03-16 16:00:21.232 Detail, Node004, Removing current message
2019-03-16 16:00:21.232 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.232 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.232 Detail,
2019-03-16 16:00:21.232 Info, Node004, Sending (Send) message (Callback ID=0x1a, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x28, 0x25, 0x1a, 0xc5
2019-03-16 16:00:21.240 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:21.240 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.260 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x1a, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x2e
2019-03-16 16:00:21.260 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x1a received (expected 0x1a)
2019-03-16 16:00:21.260 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:21.260 Detail,   Expected callbackId was received
2019-03-16 16:00:21.273 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x00, 0xbb, 0x00, 0x00, 0xc3, 0x00, 0x62
2019-03-16 16:00:21.273 Detail,
2019-03-16 16:00:21.273 Info, Node004, Response RTT 40 Average Response RTT 40
2019-03-16 16:00:21.273 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:21.273 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:21.273 Info, Node004, Received Meter report from node 4: Current=0.187A
2019-03-16 16:00:21.274 Detail, Node004, Initial read of value
2019-03-16 16:00:21.274 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.274 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.274 Detail,
2019-03-16 16:00:21.274 Detail, Node004, Removing current message
2019-03-16 16:00:21.274 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.274 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.274 Detail,
2019-03-16 16:00:21.274 Info, Node004, Sending (Send) message (Callback ID=0x1b, Expected Reply=0x04) - AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x04, 0x01, 0x25, 0x1b, 0xaf
2019-03-16 16:00:21.281 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:21.282 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.301 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x1b, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x2f
2019-03-16 16:00:21.301 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x1b received (expected 0x1b)
2019-03-16 16:00:21.302 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:21.302 Detail,   Expected callbackId was received
2019-03-16 16:00:21.315 Detail, Node004,   Received: 0x01, 0x11, 0x00, 0x04, 0x00, 0x04, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xff, 0x04, 0xfe, 0x00, 0xc3, 0x00, 0x55
2019-03-16 16:00:21.315 Detail,
2019-03-16 16:00:21.315 Info, Node004, Response RTT 41 Average Response RTT 40
2019-03-16 16:00:21.315 Info, Node004, Received Alarm report: type=0, level=0, sensorSrcID=0, type:Heat event:254, status=255
2019-03-16 16:00:21.316 Detail, Node004, Initial read of value
2019-03-16 16:00:21.316 Detail, Node004, Initial read of value
2019-03-16 16:00:21.316 Detail, Node004, Initial read of value
2019-03-16 16:00:21.316 Detail, Node004, Initial read of value
2019-03-16 16:00:21.316 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.316 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.316 Detail,
2019-03-16 16:00:21.316 Detail, Node004, Removing current message
2019-03-16 16:00:21.316 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.316 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.316 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.316 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.316 Detail,
2019-03-16 16:00:21.316 Info, Node004, Sending (Send) message (Callback ID=0x1c, Expected Reply=0x04) - AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x08, 0x01, 0x25, 0x1c, 0xa4
2019-03-16 16:00:21.324 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:21.324 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.343 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x1c, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x28
2019-03-16 16:00:21.344 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x1c received (expected 0x1c)
2019-03-16 16:00:21.344 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:21.344 Detail,   Expected callbackId was received
2019-03-16 16:00:21.360 Detail, Node004,   Received: 0x01, 0x11, 0x00, 0x04, 0x00, 0x04, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xff, 0x08, 0xfe, 0x00, 0xc3, 0x00, 0x59
2019-03-16 16:00:21.360 Detail,
2019-03-16 16:00:21.360 Info, Node004, Response RTT 43 Average Response RTT 41
2019-03-16 16:00:21.360 Info, Node004, Received Alarm report: type=0, level=0, sensorSrcID=0, type:Power Management event:254, status=255
2019-03-16 16:00:21.360 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:21.360 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:21.360 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:21.360 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:21.360 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:21.360 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:21.360 Detail, Node004, Initial read of value
2019-03-16 16:00:21.360 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.361 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.361 Detail,
2019-03-16 16:00:21.361 Detail, Node004, Removing current message
2019-03-16 16:00:21.361 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.361 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.361 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.361 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.361 Detail,
2019-03-16 16:00:21.361 Info, Node004, Sending (Send) message (Callback ID=0x1d, Expected Reply=0x04) - ClockCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x81, 0x05, 0x25, 0x1d, 0x5f
2019-03-16 16:00:21.368 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:21.368 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:21.388 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x1d, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x29
2019-03-16 16:00:21.388 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x1d received (expected 0x1d)
2019-03-16 16:00:21.388 Info, Node004, Request RTT 26 Average Request RTT 26
2019-03-16 16:00:21.388 Detail,   Expected callbackId was received
2019-03-16 16:00:21.400 Detail, Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x04, 0x81, 0x06, 0xd1, 0x00, 0xc3, 0x00, 0x62
2019-03-16 16:00:21.401 Detail,
2019-03-16 16:00:21.401 Info, Node004, Response RTT 39 Average Response RTT 40
2019-03-16 16:00:21.401 Info, Node004, Received Clock report: Saturday 17:00
2019-03-16 16:00:21.401 Detail, Node004, Initial read of value
2019-03-16 16:00:21.401 Detail, Node004, Initial read of value
2019-03-16 16:00:21.401 Detail, Node004, Initial read of value
2019-03-16 16:00:21.401 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:21.401 Detail, Node004,   Message transaction complete
2019-03-16 16:00:21.401 Detail,
2019-03-16 16:00:21.401 Detail, Node004, Removing current message
2019-03-16 16:00:21.401 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.401 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.401 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:21.401 Detail, Node004, Query Stage Complete (Dynamic)
2019-03-16 16:00:21.401 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2019-03-16 16:00:21.401 Detail, Node004, QueryStage_Configuration
2019-03-16 16:00:21.401 Detail, Node004, QueryStage_Complete
2019-03-16 16:00:21.401 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2019-03-16 16:00:21.401 Warning, CheckCompletedNodeQueries all=1, deadFound=1 sleepingOnly=1
2019-03-16 16:00:21.401 Info,          Node query processing complete except for dead nodes.
2019-03-16 16:00:21.402 Detail, Node004, Notification: NodeQueriesComplete
2019-03-16 16:00:21.402 Detail, contrlr, Notification: AllNodesQueriedSomeDead
2019-03-16 16:00:22.655 Detail,   Received: 0x01, 0x18, 0x00, 0x13, 0x1d, 0x01, 0x01, 0xbb, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x2e
2019-03-16 16:00:22.655 Detail,   ZW_SEND_DATA Request with callback ID 0x1d received (expected 0x00)
2019-03-16 16:00:22.655 Warning, WARNING: Unexpected Callback ID received
2019-03-16 16:00:27.873 Info, Node004, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2019-03-16 16:00:27.873 Info, Node004, SwitchBinary::Set - Setting node 4 to Off
2019-03-16 16:00:27.873 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x1e, 0xfe
2019-03-16 16:00:27.873 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x1f, 0xfe
2019-03-16 16:00:27.874 Detail,
2019-03-16 16:00:27.874 Info, Node004, Sending (Send) message (Callback ID=0x1e, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x1e, 0xfe
2019-03-16 16:00:27.881 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:27.881 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:27.901 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x1e, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x2a
2019-03-16 16:00:27.901 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x1e received (expected 0x1e)
2019-03-16 16:00:27.901 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:27.901 Detail,   Expected callbackId was received
2019-03-16 16:00:27.901 Detail,   Expected reply was received
2019-03-16 16:00:27.901 Detail,   Message transaction complete
2019-03-16 16:00:27.901 Detail,
2019-03-16 16:00:27.901 Detail, Node004, Removing current message
2019-03-16 16:00:27.902 Detail,
2019-03-16 16:00:27.902 Info, Node004, Sending (Send) message (Callback ID=0x1f, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x1f, 0xfe
2019-03-16 16:00:27.909 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:27.909 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:27.929 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x1f, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x2b
2019-03-16 16:00:27.929 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x1f received (expected 0x1f)
2019-03-16 16:00:27.929 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:27.929 Detail,   Expected callbackId was received
2019-03-16 16:00:27.941 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0xff, 0xc3, 0x00, 0xed
2019-03-16 16:00:27.942 Detail,
2019-03-16 16:00:27.942 Info, Node004, Response RTT 39 Average Response RTT 39
2019-03-16 16:00:27.942 Info, Node004, Received SwitchBinary report from node 4: level=On
2019-03-16 16:00:27.942 Detail, Node004, Refreshed Value: old value=true, new value=true, type=bool
2019-03-16 16:00:27.942 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:27.942 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:27.942 Detail, Node004,   Message transaction complete
2019-03-16 16:00:27.942 Detail,
2019-03-16 16:00:27.942 Detail, Node004, Removing current message
2019-03-16 16:00:27.942 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:28.723 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x00, 0x27, 0x0c, 0x00, 0x08, 0x00, 0x00, 0x27, 0x0c, 0xc3, 0x00, 0x59
2019-03-16 16:00:28.723 Detail,
2019-03-16 16:00:28.723 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:28.723 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:28.723 Info, Node004, Received Meter report from node 4: Energy=9.996kWh
2019-03-16 16:00:28.723 Detail, Node004, Refreshed Value: old value=9.996, new value=9.996, type=decimal
2019-03-16 16:00:28.723 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:28.723 Info, Node004,     Previous value was 9.996kWh, received 8 seconds ago.
2019-03-16 16:00:28.724 Detail, Node004, Refreshed Value: old value=9.996, new value=9.996, type=decimal
2019-03-16 16:00:28.724 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:28.724 Detail, Node004, Refreshed Value: old value=17, new value=8, type=int
2019-03-16 16:00:28.724 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:28.724 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:28.724 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:28.724 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:28.724 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:28.822 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x7f, 0xa4, 0x00, 0x00, 0xc3, 0x00, 0x9a
2019-03-16 16:00:28.822 Detail,
2019-03-16 16:00:28.822 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:28.823 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:28.823 Info, Node004, Received Meter report from node 4: Power=32.676W
2019-03-16 16:00:28.823 Detail, Node004, Refreshed Value: old value=32.353, new value=32.676, type=decimal
2019-03-16 16:00:28.823 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:28.823 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:28.823 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:28.922 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x64, 0x00, 0x03, 0x80, 0xa4, 0x00, 0x00, 0xc3, 0x00, 0xf6
2019-03-16 16:00:28.922 Detail,
2019-03-16 16:00:28.922 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:28.922 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:28.923 Info, Node004, Received Meter report from node 4: Voltage=229.540V
2019-03-16 16:00:28.923 Detail, Node004, Refreshed Value: old value=229.494, new value=229.540, type=decimal
2019-03-16 16:00:28.923 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:28.923 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:28.923 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.022 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x00, 0x07, 0x00, 0x00, 0xc3, 0x00, 0xde
2019-03-16 16:00:29.022 Detail,
2019-03-16 16:00:29.022 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:29.022 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.022 Info, Node004, Received Meter report from node 4: Current=0.007A
2019-03-16 16:00:29.022 Detail, Node004, Refreshed Value: old value=0.187, new value=0.007, type=decimal
2019-03-16 16:00:29.023 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.023 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.023 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.622 Detail, Node004,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x04, 0x02, 0x82, 0x01, 0xc3, 0x00, 0xb7
2019-03-16 16:00:29.623 Detail,
2019-03-16 16:00:29.623 Info, Node004, Received Hail command from node 4
2019-03-16 16:00:29.623 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x20, 0xc1
2019-03-16 16:00:29.623 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x00, 0x25, 0x21, 0xd6
2019-03-16 16:00:29.623 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x10, 0x25, 0x22, 0xc5
2019-03-16 16:00:29.623 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x20, 0x25, 0x23, 0xf4
2019-03-16 16:00:29.623 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x28, 0x25, 0x24, 0xfb
2019-03-16 16:00:29.624 Detail, Node004, Queuing (Send) AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x04, 0x01, 0x25, 0x25, 0x91
2019-03-16 16:00:29.624 Detail, Node004, Queuing (Send) AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x08, 0x01, 0x25, 0x26, 0x9e
2019-03-16 16:00:29.624 Detail, Node004, Queuing (Send) ClockCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x81, 0x05, 0x25, 0x27, 0x65
2019-03-16 16:00:29.624 Detail,
2019-03-16 16:00:29.624 Info, Node004, Sending (Send) message (Callback ID=0x20, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x20, 0xc1
2019-03-16 16:00:29.631 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:29.632 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:29.651 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x20, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x14
2019-03-16 16:00:29.651 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x20 received (expected 0x20)
2019-03-16 16:00:29.651 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:29.652 Detail,   Expected callbackId was received
2019-03-16 16:00:29.663 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0x00, 0xc3, 0x00, 0x12
2019-03-16 16:00:29.664 Detail,
2019-03-16 16:00:29.664 Info, Node004, Response RTT 39 Average Response RTT 39
2019-03-16 16:00:29.664 Info, Node004, Received SwitchBinary report from node 4: level=Off
2019-03-16 16:00:29.664 Detail, Node004, Refreshed Value: old value=true, new value=false, type=bool
2019-03-16 16:00:29.664 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.664 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:29.664 Detail, Node004,   Message transaction complete
2019-03-16 16:00:29.664 Detail,
2019-03-16 16:00:29.664 Detail, Node004, Removing current message
2019-03-16 16:00:29.664 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.664 Detail,
2019-03-16 16:00:29.665 Info, Node004, Sending (Send) message (Callback ID=0x21, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x00, 0x25, 0x21, 0xd6
2019-03-16 16:00:29.672 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:29.672 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:29.692 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x21, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x15
2019-03-16 16:00:29.692 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x21 received (expected 0x21)
2019-03-16 16:00:29.692 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:29.692 Detail,   Expected callbackId was received
2019-03-16 16:00:29.707 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x00, 0x27, 0x0c, 0x00, 0x01, 0x00, 0x00, 0x27, 0x0c, 0xc3, 0x00, 0x50
2019-03-16 16:00:29.707 Detail,
2019-03-16 16:00:29.707 Info, Node004, Response RTT 42 Average Response RTT 40
2019-03-16 16:00:29.707 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:29.707 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.707 Info, Node004, Received Meter report from node 4: Energy=9.996kWh
2019-03-16 16:00:29.707 Detail, Node004, Refreshed Value: old value=9.996, new value=9.996, type=decimal
2019-03-16 16:00:29.707 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.707 Info, Node004,     Previous value was 9.996kWh, received 1 seconds ago.
2019-03-16 16:00:29.707 Detail, Node004, Refreshed Value: old value=9.996, new value=9.996, type=decimal
2019-03-16 16:00:29.707 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.707 Detail, Node004, Refreshed Value: old value=8, new value=1, type=int
2019-03-16 16:00:29.707 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.707 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:29.708 Detail, Node004,   Message transaction complete
2019-03-16 16:00:29.708 Detail,
2019-03-16 16:00:29.708 Detail, Node004, Removing current message
2019-03-16 16:00:29.708 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.708 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.708 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.708 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.708 Detail,
2019-03-16 16:00:29.708 Info, Node004, Sending (Send) message (Callback ID=0x22, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x10, 0x25, 0x22, 0xc5
2019-03-16 16:00:29.716 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:29.716 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:29.736 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x22, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x16
2019-03-16 16:00:29.736 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x22 received (expected 0x22)
2019-03-16 16:00:29.736 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:29.736 Detail,   Expected callbackId was received
2019-03-16 16:00:29.752 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x06, 0x43, 0x00, 0x00, 0xc3, 0x00, 0x04
2019-03-16 16:00:29.753 Detail,
2019-03-16 16:00:29.753 Info, Node004, Response RTT 44 Average Response RTT 42
2019-03-16 16:00:29.753 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:29.753 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.753 Info, Node004, Received Meter report from node 4: Power=1.603W
2019-03-16 16:00:29.753 Detail, Node004, Refreshed Value: old value=32.676, new value=1.603, type=decimal
2019-03-16 16:00:29.753 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.753 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:29.753 Detail, Node004,   Message transaction complete
2019-03-16 16:00:29.753 Detail,
2019-03-16 16:00:29.753 Detail, Node004, Removing current message
2019-03-16 16:00:29.753 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.753 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.754 Detail,
2019-03-16 16:00:29.754 Info, Node004, Sending (Send) message (Callback ID=0x23, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x20, 0x25, 0x23, 0xf4
2019-03-16 16:00:29.762 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:29.762 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:29.782 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x23, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x17
2019-03-16 16:00:29.782 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x23 received (expected 0x23)
2019-03-16 16:00:29.782 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:29.782 Detail,   Expected callbackId was received
2019-03-16 16:00:29.795 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x64, 0x00, 0x03, 0x7f, 0xe9, 0x00, 0x00, 0xc3, 0x00, 0x44
2019-03-16 16:00:29.795 Detail,
2019-03-16 16:00:29.795 Info, Node004, Response RTT 40 Average Response RTT 41
2019-03-16 16:00:29.795 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:29.795 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.795 Info, Node004, Received Meter report from node 4: Voltage=229.353V
2019-03-16 16:00:29.795 Detail, Node004, Refreshed Value: old value=229.540, new value=229.353, type=decimal
2019-03-16 16:00:29.795 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.795 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:29.796 Detail, Node004,   Message transaction complete
2019-03-16 16:00:29.796 Detail,
2019-03-16 16:00:29.796 Detail, Node004, Removing current message
2019-03-16 16:00:29.796 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.796 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.796 Detail,
2019-03-16 16:00:29.796 Info, Node004, Sending (Send) message (Callback ID=0x24, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x28, 0x25, 0x24, 0xfb
2019-03-16 16:00:29.803 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:29.803 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:29.823 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x24, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x10
2019-03-16 16:00:29.823 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x24 received (expected 0x24)
2019-03-16 16:00:29.823 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:29.823 Detail,   Expected callbackId was received
2019-03-16 16:00:29.836 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x00, 0x07, 0x00, 0x00, 0xc3, 0x00, 0xde
2019-03-16 16:00:29.837 Detail,
2019-03-16 16:00:29.837 Info, Node004, Response RTT 40 Average Response RTT 40
2019-03-16 16:00:29.837 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:29.837 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.837 Info, Node004, Received Meter report from node 4: Current=0.007A
2019-03-16 16:00:29.837 Detail, Node004, Refreshed Value: old value=0.007, new value=0.007, type=decimal
2019-03-16 16:00:29.837 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.837 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:29.837 Detail, Node004,   Message transaction complete
2019-03-16 16:00:29.837 Detail,
2019-03-16 16:00:29.837 Detail, Node004, Removing current message
2019-03-16 16:00:29.837 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.837 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.837 Detail,
2019-03-16 16:00:29.837 Info, Node004, Sending (Send) message (Callback ID=0x25, Expected Reply=0x04) - AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x04, 0x01, 0x25, 0x25, 0x91
2019-03-16 16:00:29.845 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:29.845 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:29.865 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x25, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x11
2019-03-16 16:00:29.865 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x25 received (expected 0x25)
2019-03-16 16:00:29.865 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:29.865 Detail,   Expected callbackId was received
2019-03-16 16:00:29.881 Detail, Node004,   Received: 0x01, 0x11, 0x00, 0x04, 0x00, 0x04, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xff, 0x04, 0xfe, 0x00, 0xc3, 0x00, 0x55
2019-03-16 16:00:29.881 Detail,
2019-03-16 16:00:29.881 Info, Node004, Response RTT 43 Average Response RTT 41
2019-03-16 16:00:29.881 Info, Node004, Received Alarm report: type=0, level=0, sensorSrcID=0, type:Heat event:254, status=255
2019-03-16 16:00:29.881 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:29.881 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.881 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:29.881 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.881 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:29.882 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.882 Detail, Node004, Refreshed Value: old value=254, new value=254, type=byte
2019-03-16 16:00:29.882 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.882 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:29.882 Detail, Node004,   Message transaction complete
2019-03-16 16:00:29.882 Detail,
2019-03-16 16:00:29.882 Detail, Node004, Removing current message
2019-03-16 16:00:29.882 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.882 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.882 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.882 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.883 Detail,
2019-03-16 16:00:29.883 Info, Node004, Sending (Send) message (Callback ID=0x26, Expected Reply=0x04) - AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x08, 0x01, 0x25, 0x26, 0x9e
2019-03-16 16:00:29.891 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:29.891 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:29.911 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x26, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x12
2019-03-16 16:00:29.911 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x26 received (expected 0x26)
2019-03-16 16:00:29.911 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:29.911 Detail,   Expected callbackId was received
2019-03-16 16:00:29.924 Detail, Node004,   Received: 0x01, 0x11, 0x00, 0x04, 0x00, 0x04, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xff, 0x08, 0xfe, 0x00, 0xc3, 0x00, 0x59
2019-03-16 16:00:29.925 Detail,
2019-03-16 16:00:29.925 Info, Node004, Response RTT 41 Average Response RTT 41
2019-03-16 16:00:29.925 Info, Node004, Received Alarm report: type=0, level=0, sensorSrcID=0, type:Power Management event:254, status=255
2019-03-16 16:00:29.925 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:29.925 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.925 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:29.925 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.925 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:29.925 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.925 Detail, Node004, Refreshed Value: old value=254, new value=254, type=byte
2019-03-16 16:00:29.925 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.925 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:29.925 Detail, Node004,   Message transaction complete
2019-03-16 16:00:29.925 Detail,
2019-03-16 16:00:29.925 Detail, Node004, Removing current message
2019-03-16 16:00:29.925 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.925 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.926 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.926 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.926 Detail,
2019-03-16 16:00:29.926 Info, Node004, Sending (Send) message (Callback ID=0x27, Expected Reply=0x04) - ClockCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x81, 0x05, 0x25, 0x27, 0x65
2019-03-16 16:00:29.934 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:29.934 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:29.954 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x27, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x13
2019-03-16 16:00:29.954 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x27 received (expected 0x27)
2019-03-16 16:00:29.954 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:29.954 Detail,   Expected callbackId was received
2019-03-16 16:00:29.970 Detail, Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x04, 0x81, 0x06, 0xd1, 0x00, 0xc3, 0x00, 0x62
2019-03-16 16:00:29.970 Detail,
2019-03-16 16:00:29.970 Info, Node004, Response RTT 43 Average Response RTT 42
2019-03-16 16:00:29.970 Info, Node004, Received Clock report: Saturday 17:00
2019-03-16 16:00:29.970 Detail, Node004, Refreshed Value: old value=5, new value=5, type=list
2019-03-16 16:00:29.970 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.970 Detail, Node004, Refreshed Value: old value=17, new value=17, type=byte
2019-03-16 16:00:29.970 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.970 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:29.971 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:29.971 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:29.971 Detail, Node004,   Message transaction complete
2019-03-16 16:00:29.971 Detail,
2019-03-16 16:00:29.971 Detail, Node004, Removing current message
2019-03-16 16:00:29.971 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.971 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:29.971 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:39.805 Info, Node004, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - True
2019-03-16 16:00:39.805 Info, Node004, SwitchBinary::Set - Setting node 4 to On
2019-03-16 16:00:39.805 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0xff, 0x25, 0x28, 0x37
2019-03-16 16:00:39.805 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x29, 0xc8
2019-03-16 16:00:39.805 Detail,
2019-03-16 16:00:39.805 Info, Node004, Sending (Send) message (Callback ID=0x28, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0xff, 0x25, 0x28, 0x37
2019-03-16 16:00:39.813 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:39.813 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:39.833 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x28, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x1c
2019-03-16 16:00:39.833 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x28 received (expected 0x28)
2019-03-16 16:00:39.833 Info, Node004, Request RTT 27 Average Request RTT 26
2019-03-16 16:00:39.833 Detail,   Expected callbackId was received
2019-03-16 16:00:39.833 Detail,   Expected reply was received
2019-03-16 16:00:39.833 Detail,   Message transaction complete
2019-03-16 16:00:39.833 Detail,
2019-03-16 16:00:39.833 Detail, Node004, Removing current message
2019-03-16 16:00:39.833 Detail,
2019-03-16 16:00:39.834 Info, Node004, Sending (Send) message (Callback ID=0x29, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x29, 0xc8
2019-03-16 16:00:39.841 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:39.841 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:39.888 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x29, 0x00, 0x00, 0x05, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x1a
2019-03-16 16:00:39.888 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x29 received (expected 0x29)
2019-03-16 16:00:39.888 Info, Node004, Request RTT 54 Average Request RTT 40
2019-03-16 16:00:39.888 Detail,   Expected callbackId was received
2019-03-16 16:00:39.901 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0xff, 0xc3, 0x00, 0xed
2019-03-16 16:00:39.901 Detail,
2019-03-16 16:00:39.901 Info, Node004, Response RTT 67 Average Response RTT 54
2019-03-16 16:00:39.901 Info, Node004, Received SwitchBinary report from node 4: level=On
2019-03-16 16:00:39.901 Detail, Node004, Refreshed Value: old value=false, new value=true, type=bool
2019-03-16 16:00:39.901 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:39.901 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:39.901 Detail, Node004,   Message transaction complete
2019-03-16 16:00:39.901 Detail,
2019-03-16 16:00:39.901 Detail, Node004, Removing current message
2019-03-16 16:00:39.901 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.623 Detail, Node004,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x04, 0x02, 0x82, 0x01, 0xc3, 0x00, 0xb7
2019-03-16 16:00:42.623 Detail,
2019-03-16 16:00:42.623 Info, Node004, Received Hail command from node 4
2019-03-16 16:00:42.623 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2a, 0xcb
2019-03-16 16:00:42.623 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x00, 0x25, 0x2b, 0xdc
2019-03-16 16:00:42.623 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x10, 0x25, 0x2c, 0xcb
2019-03-16 16:00:42.623 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x20, 0x25, 0x2d, 0xfa
2019-03-16 16:00:42.623 Detail, Node004, Queuing (Send) MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x28, 0x25, 0x2e, 0xf1
2019-03-16 16:00:42.623 Detail, Node004, Queuing (Send) AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x04, 0x01, 0x25, 0x2f, 0x9b
2019-03-16 16:00:42.624 Detail, Node004, Queuing (Send) AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x08, 0x01, 0x25, 0x30, 0x88
2019-03-16 16:00:42.624 Detail, Node004, Queuing (Send) ClockCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x81, 0x05, 0x25, 0x31, 0x73
2019-03-16 16:00:42.624 Detail,
2019-03-16 16:00:42.624 Info, Node004, Sending (Send) message (Callback ID=0x2a, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2a, 0xcb
2019-03-16 16:00:42.631 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:42.631 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:42.651 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x2a, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x1e
2019-03-16 16:00:42.651 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x2a received (expected 0x2a)
2019-03-16 16:00:42.651 Info, Node004, Request RTT 26 Average Request RTT 33
2019-03-16 16:00:42.651 Detail,   Expected callbackId was received
2019-03-16 16:00:42.663 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0xff, 0xc3, 0x00, 0xed
2019-03-16 16:00:42.663 Detail,
2019-03-16 16:00:42.663 Info, Node004, Response RTT 39 Average Response RTT 46
2019-03-16 16:00:42.663 Info, Node004, Received SwitchBinary report from node 4: level=On
2019-03-16 16:00:42.663 Detail, Node004, Refreshed Value: old value=true, new value=true, type=bool
2019-03-16 16:00:42.663 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.663 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:42.663 Detail, Node004,   Message transaction complete
2019-03-16 16:00:42.663 Detail,
2019-03-16 16:00:42.664 Detail, Node004, Removing current message
2019-03-16 16:00:42.664 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.664 Detail,
2019-03-16 16:00:42.664 Info, Node004, Sending (Send) message (Callback ID=0x2b, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x00, 0x25, 0x2b, 0xdc
2019-03-16 16:00:42.671 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:42.671 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:42.691 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x2b, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x1f
2019-03-16 16:00:42.691 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x2b received (expected 0x2b)
2019-03-16 16:00:42.691 Info, Node004, Request RTT 27 Average Request RTT 30
2019-03-16 16:00:42.691 Detail,   Expected callbackId was received
2019-03-16 16:00:42.705 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x00, 0x27, 0x0c, 0x00, 0x0d, 0x00, 0x00, 0x27, 0x0c, 0xc3, 0x00, 0x5c
2019-03-16 16:00:42.705 Detail,
2019-03-16 16:00:42.706 Info, Node004, Response RTT 41 Average Response RTT 43
2019-03-16 16:00:42.706 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:42.706 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.706 Info, Node004, Received Meter report from node 4: Energy=9.996kWh
2019-03-16 16:00:42.706 Detail, Node004, Refreshed Value: old value=9.996, new value=9.996, type=decimal
2019-03-16 16:00:42.706 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.706 Info, Node004,     Previous value was 9.996kWh, received 13 seconds ago.
2019-03-16 16:00:42.706 Detail, Node004, Refreshed Value: old value=9.996, new value=9.996, type=decimal
2019-03-16 16:00:42.706 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.706 Detail, Node004, Refreshed Value: old value=1, new value=13, type=int
2019-03-16 16:00:42.706 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.706 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:42.706 Detail, Node004,   Message transaction complete
2019-03-16 16:00:42.706 Detail,
2019-03-16 16:00:42.706 Detail, Node004, Removing current message
2019-03-16 16:00:42.706 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.706 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.706 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.706 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.709 Detail,
2019-03-16 16:00:42.709 Info, Node004, Sending (Send) message (Callback ID=0x2c, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x10, 0x25, 0x2c, 0xcb
2019-03-16 16:00:42.717 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:42.717 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:42.737 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x2c, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x18
2019-03-16 16:00:42.737 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x2c received (expected 0x2c)
2019-03-16 16:00:42.737 Info, Node004, Request RTT 27 Average Request RTT 28
2019-03-16 16:00:42.737 Detail,   Expected callbackId was received
2019-03-16 16:00:42.752 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x00, 0x0a, 0x23, 0x00, 0x00, 0xc3, 0x00, 0x68
2019-03-16 16:00:42.752 Detail,
2019-03-16 16:00:42.752 Info, Node004, Response RTT 43 Average Response RTT 43
2019-03-16 16:00:42.752 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:42.752 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.752 Info, Node004, Received Meter report from node 4: Power=2.595W
2019-03-16 16:00:42.752 Detail, Node004, Refreshed Value: old value=1.603, new value=2.595, type=decimal
2019-03-16 16:00:42.753 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.753 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:42.753 Detail, Node004,   Message transaction complete
2019-03-16 16:00:42.753 Detail,
2019-03-16 16:00:42.753 Detail, Node004, Removing current message
2019-03-16 16:00:42.753 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.753 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.753 Detail,
2019-03-16 16:00:42.753 Info, Node004, Sending (Send) message (Callback ID=0x2d, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x20, 0x25, 0x2d, 0xfa
2019-03-16 16:00:42.760 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:42.760 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:42.780 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x2d, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x19
2019-03-16 16:00:42.780 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x2d received (expected 0x2d)
2019-03-16 16:00:42.781 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:42.781 Detail,   Expected callbackId was received
2019-03-16 16:00:42.794 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x64, 0x00, 0x03, 0x80, 0xd3, 0x00, 0x00, 0xc3, 0x00, 0x81
2019-03-16 16:00:42.794 Detail,
2019-03-16 16:00:42.794 Info, Node004, Response RTT 40 Average Response RTT 41
2019-03-16 16:00:42.794 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:42.794 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.794 Info, Node004, Received Meter report from node 4: Voltage=229.587V
2019-03-16 16:00:42.794 Detail, Node004, Refreshed Value: old value=229.353, new value=229.587, type=decimal
2019-03-16 16:00:42.794 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.794 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:42.794 Detail, Node004,   Message transaction complete
2019-03-16 16:00:42.794 Detail,
2019-03-16 16:00:42.794 Detail, Node004, Removing current message
2019-03-16 16:00:42.794 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.795 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.795 Detail,
2019-03-16 16:00:42.796 Info, Node004, Sending (Send) message (Callback ID=0x2e, Expected Reply=0x04) - MeterCmd_Get (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x32, 0x01, 0x28, 0x25, 0x2e, 0xf1
2019-03-16 16:00:42.803 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:42.803 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:42.823 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x2e, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x1a
2019-03-16 16:00:42.823 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x2e received (expected 0x2e)
2019-03-16 16:00:42.823 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:42.823 Detail,   Expected callbackId was received
2019-03-16 16:00:42.837 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x00, 0xb9, 0x00, 0x00, 0xc3, 0x00, 0x60
2019-03-16 16:00:42.837 Detail,
2019-03-16 16:00:42.837 Info, Node004, Response RTT 41 Average Response RTT 41
2019-03-16 16:00:42.837 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-03-16 16:00:42.837 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.837 Info, Node004, Received Meter report from node 4: Current=0.185A
2019-03-16 16:00:42.838 Detail, Node004, Refreshed Value: old value=0.007, new value=0.185, type=decimal
2019-03-16 16:00:42.838 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.838 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:42.838 Detail, Node004,   Message transaction complete
2019-03-16 16:00:42.838 Detail,
2019-03-16 16:00:42.838 Detail, Node004, Removing current message
2019-03-16 16:00:42.838 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.838 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.838 Detail,
2019-03-16 16:00:42.838 Info, Node004, Sending (Send) message (Callback ID=0x2f, Expected Reply=0x04) - AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x04, 0x01, 0x25, 0x2f, 0x9b
2019-03-16 16:00:42.846 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:42.846 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:42.866 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x2f, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x1b
2019-03-16 16:00:42.866 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x2f received (expected 0x2f)
2019-03-16 16:00:42.866 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:42.866 Detail,   Expected callbackId was received
2019-03-16 16:00:42.882 Detail, Node004,   Received: 0x01, 0x11, 0x00, 0x04, 0x00, 0x04, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xff, 0x04, 0xfe, 0x00, 0xc3, 0x00, 0x55
2019-03-16 16:00:42.882 Detail,
2019-03-16 16:00:42.882 Info, Node004, Response RTT 43 Average Response RTT 42
2019-03-16 16:00:42.882 Info, Node004, Received Alarm report: type=0, level=0, sensorSrcID=0, type:Heat event:254, status=255
2019-03-16 16:00:42.882 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:42.882 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.882 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:42.882 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.882 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:42.882 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.883 Detail, Node004, Refreshed Value: old value=254, new value=254, type=byte
2019-03-16 16:00:42.883 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.883 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:42.883 Detail, Node004,   Message transaction complete
2019-03-16 16:00:42.883 Detail,
2019-03-16 16:00:42.883 Detail, Node004, Removing current message
2019-03-16 16:00:42.883 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.883 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.883 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.883 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.883 Detail,
2019-03-16 16:00:42.883 Info, Node004, Sending (Send) message (Callback ID=0x30, Expected Reply=0x04) - AlarmCmd_Get (Node=4): 0x01, 0x0c, 0x00, 0x13, 0x04, 0x05, 0x71, 0x04, 0x00, 0x08, 0x01, 0x25, 0x30, 0x88
2019-03-16 16:00:42.891 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:42.891 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:42.911 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x30, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x04
2019-03-16 16:00:42.911 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x30 received (expected 0x30)
2019-03-16 16:00:42.911 Info, Node004, Request RTT 27 Average Request RTT 27
2019-03-16 16:00:42.911 Detail,   Expected callbackId was received
2019-03-16 16:00:42.925 Detail, Node004,   Received: 0x01, 0x11, 0x00, 0x04, 0x00, 0x04, 0x09, 0x71, 0x05, 0x00, 0x00, 0x00, 0xff, 0x08, 0xfe, 0x00, 0xc3, 0x00, 0x59
2019-03-16 16:00:42.925 Detail,
2019-03-16 16:00:42.925 Info, Node004, Response RTT 41 Average Response RTT 41
2019-03-16 16:00:42.925 Info, Node004, Received Alarm report: type=0, level=0, sensorSrcID=0, type:Power Management event:254, status=255
2019-03-16 16:00:42.925 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:42.925 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.925 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:42.925 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.925 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:42.925 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.926 Detail, Node004, Refreshed Value: old value=254, new value=254, type=byte
2019-03-16 16:00:42.926 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.926 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:42.926 Detail, Node004,   Message transaction complete
2019-03-16 16:00:42.926 Detail,
2019-03-16 16:00:42.926 Detail, Node004, Removing current message
2019-03-16 16:00:42.926 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.926 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.926 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.926 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.926 Detail,
2019-03-16 16:00:42.926 Info, Node004, Sending (Send) message (Callback ID=0x31, Expected Reply=0x04) - ClockCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x81, 0x05, 0x25, 0x31, 0x73
2019-03-16 16:00:42.933 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-03-16 16:00:42.933 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-03-16 16:00:42.953 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x31, 0x00, 0x00, 0x02, 0x00, 0xc3, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x05
2019-03-16 16:00:42.953 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x31 received (expected 0x31)
2019-03-16 16:00:42.953 Info, Node004, Request RTT 26 Average Request RTT 26
2019-03-16 16:00:42.953 Detail,   Expected callbackId was received
2019-03-16 16:00:42.965 Detail, Node004,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x04, 0x04, 0x81, 0x06, 0xd1, 0x00, 0xc3, 0x00, 0x62
2019-03-16 16:00:42.966 Detail,
2019-03-16 16:00:42.966 Info, Node004, Response RTT 39 Average Response RTT 40
2019-03-16 16:00:42.966 Info, Node004, Received Clock report: Saturday 17:00
2019-03-16 16:00:42.966 Detail, Node004, Refreshed Value: old value=5, new value=5, type=list
2019-03-16 16:00:42.966 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.966 Detail, Node004, Refreshed Value: old value=17, new value=17, type=byte
2019-03-16 16:00:42.966 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.966 Detail, Node004, Refreshed Value: old value=0, new value=0, type=byte
2019-03-16 16:00:42.966 Detail, Node004, Changes to this value are not verified
2019-03-16 16:00:42.966 Detail, Node004,   Expected reply and command class was received
2019-03-16 16:00:42.966 Detail, Node004,   Message transaction complete
2019-03-16 16:00:42.966 Detail,
2019-03-16 16:00:42.966 Detail, Node004, Removing current message
2019-03-16 16:00:42.966 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.966 Detail, Node004, Notification: ValueChanged
2019-03-16 16:00:42.966 Detail, Node004, Notification: ValueChanged
sjorge picture sjorge on 16 Mar 2019

The strange thing I see there is the log
Changes to this value are not verified
I think this is a configuration problem, try to check how that value is configured in the xml configuration file (you can find it in Openzwave github under config/<manufacturername>/<productname>.xml) and post here the Vaue entry, I think there is a flag verify changes on the value that should be false.

On 16 Mar 2019, at 17:07, Jorge Schrauwen notifications@github.com wrote:

Changes to this value are not verified

robertsLando picture robertsLando on 16 Mar 2019

https://github.com/OpenZWave/open-zwave/blob/master/config/aeotec/zw116.xml

I'm not finding the switch value configuration in the xml form the repo, but is is present in zwcfg.xml (auto detected?)

                        <CommandClass id="37" name="COMMAND_CLASS_SWITCH_BINARY" version="1" request_flags="4" innif="true">
                                <Instance index="1" />
                                <Value type="bool" genre="user" instance="1" index="0" label="Switch" units="" read_only="false" write_only="false" verify_changes="false" poll_intensity="0" min="0" max="0" value="True" />
                        </CommandClass>
sjorge picture sjorge on 16 Mar 2019

Something I noticed is that the SwitchBinaryCmd_Set is followed by a SwitchBinaryCmd_Get, ...
I think the first incorrect result is from the SwitchBinaryCmd_Get and then the correct one happens a bit later and that is the callback from SwitchBinaryCmd_Set? Not sure why it is doing a _Get as I am not requesting one?

sjorge picture sjorge on 16 Mar 2019

I think this is more likely a problem related to underlying libs @sjorge, maybe Openzwave it self or the node wrapper node-openzwave-shared or simply a configuration problem, I never expected issues like this with my devices

robertsLando picture robertsLando on 25 Mar 2019

I wasn't able to track down where the SwitchBinaryCmd_Get call was coming from, it could very well be coming from the node-wrapper

sjorge picture sjorge on 25 Mar 2019

Try to edit your configuration file in that way by adding

<CommandClass id="37" name="COMMAND_CLASS_SWITCH_BINARY" version="1" request_flags="4" innif="true">
                                <Instance index="1" />
                                <Value type="bool" genre="user" instance="1" index="0" label="Switch" units="" read_only="false" write_only="false" min="0" max="0" value="True" />
                        </CommandClass>

Usually the device db path is /usr/local/etc/openzwave but you can check it on the debug logs at start: 

Initialising OpenZWave 1.4.3319 binary addon for Node.JS.
        OpenZWave Security API is ENABLED
        ZWave device db    : /usr/local/etc/openzwave

I have updated my comment because after some search I found that the previous edit I suggested is used to prevent the class support @sjorge

robertsLando picture robertsLando on 25 Mar 2019

I wasn't able to track down where the SwitchBinaryCmd_Get call was coming from, it could very well be coming from the node-wrapper

I don't think so I think it is more likely from Openzwave lib, firstly try with the xml config file edit

robertsLando picture robertsLando on 25 Mar 2019

Do I need to do anything else aside from editing the file for ZW116 and restarting?

sjorge picture sjorge on 25 Mar 2019

So I updated the file, restarted zwave2mqtt and tried again... exact same result

sjorge picture sjorge on 29 Mar 2019

You should remove and add back again the node in order to get the new configuration, or you could delete the zwcfg_...xml file that contains the device configuration stored

robertsLando picture robertsLando on 29 Mar 2019

This results in 0 updates at all :(

Old Behavior:

  • 1 update immediately (wrong)
  • 1 update delayed (correct)

New Behavior:

  • no updates
sjorge picture sjorge on 29 Mar 2019

Mmmm try with other configurations by checking other config files. At least if now you don't receive any update it means that you are working on the right value configuration.

Last thing: open an issue on OpenZwave library, maybe they can tell you how to fix this in config file or maybe it is a know bug. Explain the bug by adding also a log output with the wrong behavior. Relate the issue to this issue so they can find other info.

On 29 Mar 2019, at 17:46, Jorge Schrauwen notifications@github.com wrote:

This results in 0 updates at all :(

Old Behavior:

1 update immediately (wrong)
1 update delayed (correct)
New Behavior:

no updates
—
You are receiving this because you commented.
Reply to this email directly, view it on GitHub, or mute the thread.

robertsLando picture robertsLando on 29 Mar 2019

I've file one upstream https://github.com/OpenZWave/open-zwave/issues/1755

sjorge picture sjorge on 30 Mar 2019
👍1

@sjorge seems something related to the config xml file so, did you checked what group association you get from the device? Go to the UI control panel, select groups tab and select the node 4 from the first select than select Lifeline from the second and check if the controller is correctly paired to lifeline group (and just that group not elseone)

robertsLando picture robertsLando on 1 Apr 2019

screenshot

Looks OK to me? There is also a retransmit group...

screenshot2

Which is empty

sjorge picture sjorge on 1 Apr 2019

Yep it looks ok to me too, really I don't know what else to do now, just check config xml files of similar devices to see if there is something wrong

On 1 Apr 2019, at 17:06, Jorge Schrauwen notifications@github.com wrote:

Looks OK to me? There is also a retransmit group...

Which is empty

—
You are receiving this because you commented.
Reply to this email directly, view it on GitHub, or mute the thread.

robertsLando picture robertsLando on 1 Apr 2019

Did you see the additional reply in the upstream issue?

If the device reports its original state, and then almost immediately (via the lifeline association I assume) its final state - sorry - nothing OZW can do about it. It could be for a multitude of reasons. Perhaps if your network is congested, you will get a longer pause between the SET and GET commands and get the right report.
The only change you can try is Manager::SetChangeVerified on the specific ValueID, but that will increase the network utilization, and potentially latency and most likely, you would have recieved the Assocation Report before the change is verified anyway.

sjorge picture sjorge on 2 Apr 2019

Ok so try to use the api call : setChangeVerified(valueid) on that value to see if it works.

The other fix would be to add a flag on values like send just if change but check if the other way works

robertsLando picture robertsLando on 3 Apr 2019

Yes! This works fine with setChangeVerified set to true!
Can we get an easy way to enable/disable this for values via the UI? I guess the value list section can get an additional toggle for this maybe?

sjorge picture sjorge on 6 Apr 2019

I guess the value list section can get an additional toggle for this maybe?

Yes that's what I was thinking, wait for the next commit

robertsLando picture robertsLando on 8 Apr 2019

Hi @sjorge, please check my last commit 1fd4ef03f410ed162610ef4afc8515b6c8577b05 and let me know if your problem is fixed now

robertsLando picture robertsLando on 8 Apr 2019

Were did you add the toggle?

screenshot

sjorge picture sjorge on 8 Apr 2019

Oh I think I migh have forgot to run npm run build... retrying

sjorge picture sjorge on 8 Apr 2019

Shows up now, will try to test it later today or tomorrow

sjorge picture sjorge on 8 Apr 2019

Looks good!

sjorge picture sjorge on 8 Apr 2019
🎉1 👍1

@sjorge check https://github.com/OpenZWave/open-zwave/wiki/CommandClass-Compatibility-Flags , seems verifyChanges does that

robertsLando picture robertsLando on 27 Jun 2019

Cool, I'll check the device definition once I get the new OZW compiled. Still hoping to have time this weekend 🤞

It's nice to still have the current toggle though. Which has been working fine for a while now.

sjorge picture sjorge on 27 Jun 2019
Was this page helpful?
0 / 5 - 0 ratings

Related issues

CaCu15 picture CaCu15  Â·  9Comments
Jeoffreybauvin picture Jeoffreybauvin  Â·  4Comments
hostrup picture hostrup  Â·  4Comments
billiaz picture billiaz  Â·  8Comments
lsilva171 picture lsilva171  Â·  6Comments