[bug] "Verify Changes" seems broken

sjorge commented 5 years ago

Version

Build/Run method

[ ] Docker
[ ] PKG
[X] Manually built (git clone - npm install - npm run build )

Zwave2Mqtt version: 2.0.1-master Openzwave Version: 1.6.848

Describe the bug I have a switch ( you know the one) that does weird things on changing it. This was fixed a while ago by allow "Verify Changes" to be set for the vlaue, this worked fine on openzwave 1.4 but it is now broken again.

Split this of from #23 as I think this is fixed, with a caviat.

To Reproduce Go to the toggle and click it (a few times), with the verify changes optioned set (and did a cold restart of zwave2mqtt)

As per the log:

startup with switch off

toggle to on

2019-07-21T14:14:17.827Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-07-21T14:14:17.833Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:18.312Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true
2019-07-21T14:14:18.441Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:18.882Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:18.945Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true

This worked, but it got 4 upates (which is bad, as this is the behaviour we had without verify changes set before that feature was added)

toggle to off

2019-07-21T14:14:24.069Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-07-21T14:14:24.070Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:28.233Z z2m:Zwave Notification from node 4: Notification - TimeOut (1)
2019-07-21T14:14:28.363Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:29.014Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> false
2019-07-21T14:14:33.154Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false

The update took longer than normal, We get a true -> true state change so the UI updates the toggle back to true, then to false and then false again. Over mqtt node-red is just confused as hell.

toggle to on again

2019-07-21T14:14:33.828Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-07-21T14:14:33.829Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:34.314Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true
2019-07-21T14:14:34.358Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:37.195Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true

Same confusing dance.

toggle to off, yet again

2019-07-21T14:14:42.197Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-07-21T14:14:42.198Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:42.673Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> false
2019-07-21T14:14:42.924Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-07-21T14:14:47.044Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false

Same back and forth dance, still makes most mqtt listerens and the ui not very happy.

toggle to on, for the last tiem

2019-07-21T14:14:49.540Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-07-21T14:14:49.541Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:49.783Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-07-21T14:14:59.016Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:73.765 -> 73.765
2019-07-21T14:14:59.017Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-07-21T14:14:59.113Z z2m:Zwave zwave node 4: changed: 50-1-2:Electric - W:0 -> 73.46
2019-07-21T14:14:59.115Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-07-21T14:14:59.213Z z2m:Zwave zwave node 4: changed: 50-1-4:Electric - V:231.926 -> 232.301
2019-07-21T14:14:59.216Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-07-21T14:14:59.313Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.007 -> 0.437
2019-07-21T14:14:59.314Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-07-21T14:15:00.911Z z2m:Zwave Notification from node 4: Notification - TimeOut (1)
2019-07-21T14:15:01.327Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true
2019-07-21T14:15:01.412Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true

This happened mid update of the device, still does the weird dance.

Expected behavior The old behaviour with "verify changes" was that it would then only do 1 set of state changes for 'Switch'. But I think this 'verify changes' got removed from openzwave between 1.4 and 1.6 but I can;t find where I read that.

Additional context

[ Jul 21 14:13:52 Executing start method ("/opt/local/bin/node /opt/zwave2mqtt/bin/www"). ]
2019-07-21T14:13:53.008Z z2m:Store scenes.json not found
2019-07-21T14:13:53.397Z z2m:App Application path:/opt/zwave2mqtt
Initialising OpenZWave 1.6.848 binary addon for Node.JS.
        OpenZWave Security API is ENABLED
        ZWave device db    : /opt/local/etc/openzwave
        User settings path : /opt/zwave2mqtt/store
        Option Overrides : --Logging false --ConsoleOutput false --QueueLogLevel 6 --DriverMaxAttempts 9999 --SaveConfiguration true --PollInterval 60000 --NetworkKey <snip>
2019-07-21T14:13:53.492Z z2m:Zwave Connecting to /dev/cua/0
2019-07-21T14:13:53.902Z z2m:App Listening on port 8091
2019-07-21T14:13:53.961Z z2m:Mqtt MQTT client connected
2019-07-21T14:13:54.038Z z2m:Zwave Scanning network with homeid: 0xfc20d090
2019-07-21T14:13:54.039Z z2m:Zwave Node added 1
2019-07-21T14:13:54.040Z z2m:Zwave node 1 AVAILABLE: Z-Wave.Me - ZME_UZB1 USB Stick (Static PC Controller)
2019-07-21T14:13:54.041Z z2m:Zwave ValueAdded: 1-32-1-0 Basic
2019-07-21T14:13:54.044Z z2m:Zwave ValueAdded: 1-114-1-0 Loaded Config Revision
2019-07-21T14:13:54.044Z z2m:Zwave ValueAdded: 1-114-1-1 Config File Revision
2019-07-21T14:13:54.045Z z2m:Zwave ValueAdded: 1-114-1-2 Latest Available Config File Revision
2019-07-21T14:13:54.045Z z2m:Zwave ValueAdded: 1-114-1-3 Device ID
2019-07-21T14:13:54.046Z z2m:Zwave ValueAdded: 1-114-1-4 Serial Number
2019-07-21T14:13:54.046Z z2m:Zwave Node added 2
2019-07-21T14:13:54.048Z z2m:Zwave node 2 AVAILABLE: AEON Labs - ZW100 MultiSensor 6 (Home Security Sensor)
2019-07-21T14:13:54.048Z z2m:Zwave ValueAdded: 2-48-1-0 Sensor
2019-07-21T14:13:54.049Z z2m:Zwave ValueAdded: 2-49-1-1 Air Temperature
2019-07-21T14:13:54.049Z z2m:Zwave ValueAdded: 2-49-1-3 Illuminance
2019-07-21T14:13:54.050Z z2m:Zwave ValueAdded: 2-49-1-5 Humidity
2019-07-21T14:13:54.050Z z2m:Zwave ValueAdded: 2-49-1-27 Ultraviolet
2019-07-21T14:13:54.050Z z2m:Zwave ValueAdded: 2-49-1-256 Air Temperature Units
2019-07-21T14:13:54.051Z z2m:Zwave ValueAdded: 2-49-1-258 Illuminance Units
2019-07-21T14:13:54.052Z z2m:Zwave ValueAdded: 2-49-1-260 Humidity Units
2019-07-21T14:13:54.052Z z2m:Zwave ValueAdded: 2-49-1-282 Ultraviolet Units
2019-07-21T14:13:54.053Z z2m:Zwave ValueAdded: 2-94-1-0 ZWave+ Version
2019-07-21T14:13:54.053Z z2m:Zwave ValueAdded: 2-94-1-1 InstallerIcon
2019-07-21T14:13:54.053Z z2m:Zwave ValueAdded: 2-94-1-2 UserIcon
2019-07-21T14:13:54.054Z z2m:Zwave ValueAdded: 2-112-1-2 Wake up 10 minutes on Power On
2019-07-21T14:13:54.054Z z2m:Zwave ValueAdded: 2-112-1-3 On time
2019-07-21T14:13:54.055Z z2m:Zwave ValueAdded: 2-112-1-4 Enable Motion Sensor
2019-07-21T14:13:54.055Z z2m:Zwave ValueAdded: 2-112-1-5 Command Options
2019-07-21T14:13:54.056Z z2m:Zwave ValueAdded: 2-112-1-8 Awake timeout
2019-07-21T14:13:54.056Z z2m:Zwave ValueAdded: 2-112-1-9 Current power mode
2019-07-21T14:13:54.057Z z2m:Zwave ValueAdded: 2-112-1-39 Low Battery
2019-07-21T14:13:54.057Z z2m:Zwave ValueAdded: 2-112-1-40 Report Only On Thresholds
2019-07-21T14:13:54.058Z z2m:Zwave ValueAdded: 2-112-1-41 Temperature Reporting Threshold
2019-07-21T14:13:54.059Z z2m:Zwave ValueAdded: 2-112-1-42 Humidity Reporting Threshold
2019-07-21T14:13:54.059Z z2m:Zwave ValueAdded: 2-112-1-43 Luminance Reporting Threshold
2019-07-21T14:13:54.060Z z2m:Zwave ValueAdded: 2-112-1-44 Battery Reporting Threshold
2019-07-21T14:13:54.060Z z2m:Zwave ValueAdded: 2-112-1-45 UV Reporting Threshold
2019-07-21T14:13:54.060Z z2m:Zwave ValueAdded: 2-112-1-46 Low Temp Alarm
2019-07-21T14:13:54.061Z z2m:Zwave ValueAdded: 2-112-1-48 Enable/disable to send a report on Threshold
2019-07-21T14:13:54.061Z z2m:Zwave ValueAdded: 2-112-1-49 Set the upper limit value of temperature sensor
2019-07-21T14:13:54.062Z z2m:Zwave ValueAdded: 2-112-1-50 Set the lower limit value of temperature sensor
2019-07-21T14:13:54.062Z z2m:Zwave ValueAdded: 2-112-1-51 Set the upper limit value of humidity sensor
2019-07-21T14:13:54.063Z z2m:Zwave ValueAdded: 2-112-1-52 Set the lower limit value of humidity sensor
2019-07-21T14:13:54.063Z z2m:Zwave ValueAdded: 2-112-1-53 Set the upper limit value of Lighting sensor
2019-07-21T14:13:54.064Z z2m:Zwave ValueAdded: 2-112-1-54 Set the lower limit value of Lighting sensor
2019-07-21T14:13:54.064Z z2m:Zwave ValueAdded: 2-112-1-55 Set the upper limit value of ultraviolet sensor
2019-07-21T14:13:54.065Z z2m:Zwave ValueAdded: 2-112-1-56 Set the lower limit value of ultraviolet sensor
2019-07-21T14:13:54.065Z z2m:Zwave ValueAdded: 2-112-1-57 Set the recover limit value of temperature sensor
2019-07-21T14:13:54.066Z z2m:Zwave ValueAdded: 2-112-1-58 Set the recover limit value of humidity sensor
2019-07-21T14:13:54.066Z z2m:Zwave ValueAdded: 2-112-1-59 Set the recover limit value of Lighting sensor
2019-07-21T14:13:54.067Z z2m:Zwave ValueAdded: 2-112-1-60 Set the recover limit value of Ultraviolet sensor
2019-07-21T14:13:54.067Z z2m:Zwave ValueAdded: 2-112-1-61 Get the out-of-limit state of the Sensors
2019-07-21T14:13:54.067Z z2m:Zwave ValueAdded: 2-112-1-64 Temperature scale
2019-07-21T14:13:54.068Z z2m:Zwave ValueAdded: 2-112-1-81 LED blinking report
2019-07-21T14:13:54.068Z z2m:Zwave ValueAdded: 2-112-1-100 Reset Params 101-103 to Default
2019-07-21T14:13:54.069Z z2m:Zwave ValueAdded: 2-112-1-101 Group 1 Reports
2019-07-21T14:13:54.069Z z2m:Zwave ValueAdded: 2-112-1-102 Group 2 Reports
2019-07-21T14:13:54.070Z z2m:Zwave ValueAdded: 2-112-1-103 Group 3 Reports
2019-07-21T14:13:54.070Z z2m:Zwave ValueAdded: 2-112-1-110 Reset Params 111-113 to Default
2019-07-21T14:13:54.071Z z2m:Zwave ValueAdded: 2-112-1-111 Group 1 Interval
2019-07-21T14:13:54.071Z z2m:Zwave ValueAdded: 2-112-1-112 Group 2 Interval
2019-07-21T14:13:54.072Z z2m:Zwave ValueAdded: 2-112-1-113 Group 3 Interval
2019-07-21T14:13:54.072Z z2m:Zwave ValueAdded: 2-112-1-201 Temperature Calibration
2019-07-21T14:13:54.073Z z2m:Zwave ValueAdded: 2-112-1-202 Humidity Calibration
2019-07-21T14:13:54.073Z z2m:Zwave ValueAdded: 2-112-1-203 Luminance Calibration
2019-07-21T14:13:54.073Z z2m:Zwave ValueAdded: 2-112-1-204 Ultraviolet Calibration
2019-07-21T14:13:54.074Z z2m:Zwave ValueAdded: 2-112-1-252 Enable/disable Lock Configuration
2019-07-21T14:13:54.074Z z2m:Zwave ValueAdded: 2-112-1-255 Reset To Factory Defaults
2019-07-21T14:13:54.075Z z2m:Zwave ValueAdded: 2-113-1-7 Home Security
2019-07-21T14:13:54.075Z z2m:Zwave ValueAdded: 2-113-1-256 Previous Event Cleared
2019-07-21T14:13:54.076Z z2m:Zwave ValueAdded: 2-113-1-514 Automatically Clear Events
2019-07-21T14:13:54.076Z z2m:Zwave ValueAdded: 2-114-1-0 Loaded Config Revision
2019-07-21T14:13:54.076Z z2m:Zwave ValueAdded: 2-114-1-1 Config File Revision
2019-07-21T14:13:54.077Z z2m:Zwave ValueAdded: 2-114-1-2 Latest Available Config File Revision
2019-07-21T14:13:54.077Z z2m:Zwave ValueAdded: 2-114-1-3 Device ID
2019-07-21T14:13:54.078Z z2m:Zwave ValueAdded: 2-114-1-4 Serial Number
2019-07-21T14:13:54.078Z z2m:Zwave ValueAdded: 2-115-1-0 Powerlevel
2019-07-21T14:13:54.078Z z2m:Zwave ValueAdded: 2-115-1-1 Timeout
2019-07-21T14:13:54.079Z z2m:Zwave ValueAdded: 2-115-1-2 Set Powerlevel
2019-07-21T14:13:54.079Z z2m:Zwave ValueAdded: 2-115-1-3 Test Node
2019-07-21T14:13:54.080Z z2m:Zwave ValueAdded: 2-115-1-4 Test Powerlevel
2019-07-21T14:13:54.080Z z2m:Zwave ValueAdded: 2-115-1-5 Frame Count
2019-07-21T14:13:54.081Z z2m:Zwave ValueAdded: 2-115-1-6 Test
2019-07-21T14:13:54.081Z z2m:Zwave ValueAdded: 2-115-1-7 Report
2019-07-21T14:13:54.082Z z2m:Zwave ValueAdded: 2-115-1-8 Test Status
2019-07-21T14:13:54.082Z z2m:Zwave ValueAdded: 2-115-1-9 Acked Frames
2019-07-21T14:13:54.082Z z2m:Zwave ValueAdded: 2-128-1-0 Battery Level
2019-07-21T14:13:54.083Z z2m:Zwave ValueAdded: 2-132-1-1 Minimum Wake-up Interval
2019-07-21T14:13:54.083Z z2m:Zwave ValueAdded: 2-132-1-2 Maximum Wake-up Interval
2019-07-21T14:13:54.083Z z2m:Zwave ValueAdded: 2-132-1-3 Default Wake-up Interval
2019-07-21T14:13:54.084Z z2m:Zwave ValueAdded: 2-132-1-4 Wake-up Interval Step
2019-07-21T14:13:54.084Z z2m:Zwave ValueAdded: 2-132-1-0 Wake-up Interval
2019-07-21T14:13:54.084Z z2m:Zwave ValueAdded: 2-134-1-0 Library Version
2019-07-21T14:13:54.085Z z2m:Zwave ValueAdded: 2-134-1-1 Protocol Version
2019-07-21T14:13:54.085Z z2m:Zwave ValueAdded: 2-134-1-2 Application Version
2019-07-21T14:13:54.086Z z2m:Zwave Node added 4
2019-07-21T14:13:54.086Z z2m:Zwave node 4 AVAILABLE: AEON Labs - ZW116 Nano Switch (On/Off Power Switch)
2019-07-21T14:13:54.086Z z2m:Zwave ValueAdded: 4-37-1-0 Switch
2019-07-21T14:13:54.087Z z2m:Zwave ValueAdded: 4-39-1-0 Switch All
2019-07-21T14:13:54.087Z z2m:Zwave ValueAdded: 4-43-1-0 Scene
2019-07-21T14:13:54.088Z z2m:Zwave ValueAdded: 4-43-1-1 Duration
2019-07-21T14:13:54.088Z z2m:Zwave ValueAdded: 4-50-1-0 Electric - kWh
2019-07-21T14:13:54.089Z z2m:Zwave ValueAdded: 4-50-1-2 Electric - W
2019-07-21T14:13:54.089Z z2m:Zwave ValueAdded: 4-50-1-4 Electric - V
2019-07-21T14:13:54.089Z z2m:Zwave ValueAdded: 4-50-1-5 Electric - A
2019-07-21T14:13:54.090Z z2m:Zwave ValueAdded: 4-50-1-256 Exporting
2019-07-21T14:13:54.090Z z2m:Zwave ValueAdded: 4-50-1-257 Reset
2019-07-21T14:13:54.090Z z2m:Zwave ValueAdded: 4-94-1-0 ZWave+ Version
2019-07-21T14:13:54.091Z z2m:Zwave ValueAdded: 4-94-1-1 InstallerIcon
2019-07-21T14:13:54.091Z z2m:Zwave ValueAdded: 4-94-1-2 UserIcon
2019-07-21T14:13:54.092Z z2m:Zwave ValueAdded: 4-112-1-3 Over current protection
2019-07-21T14:13:54.092Z z2m:Zwave ValueAdded: 4-112-1-4 Over heat protection
2019-07-21T14:13:54.093Z z2m:Zwave ValueAdded: 4-112-1-20 Status re-power on
2019-07-21T14:13:54.093Z z2m:Zwave ValueAdded: 4-112-1-21 WallSwipe IR sensor
2019-07-21T14:13:54.094Z z2m:Zwave ValueAdded: 4-112-1-64 WallSwipe button color
2019-07-21T14:13:54.094Z z2m:Zwave ValueAdded: 4-112-1-65 WallSwipe LED Up gesture action
2019-07-21T14:13:54.094Z z2m:Zwave ValueAdded: 4-112-1-66 WallSwipe LED Down gesture action
2019-07-21T14:13:54.095Z z2m:Zwave ValueAdded: 4-112-1-67 WallSwipe LED Left gesture action
2019-07-21T14:13:54.095Z z2m:Zwave ValueAdded: 4-112-1-68 WallSwipe LED Right gesture action
2019-07-21T14:13:54.096Z z2m:Zwave ValueAdded: 4-112-1-69 WallSwipe Night light
2019-07-21T14:13:54.096Z z2m:Zwave ValueAdded: 4-112-1-71 WallSwipe sensitivity
2019-07-21T14:13:54.097Z z2m:Zwave ValueAdded: 4-112-1-80 Notification report association group 1
2019-07-21T14:13:54.097Z z2m:Zwave ValueAdded: 4-112-1-81 Notification report using the external switch 1
2019-07-21T14:13:54.098Z z2m:Zwave ValueAdded: 4-112-1-82 Notification report using the external switch 2
2019-07-21T14:13:54.098Z z2m:Zwave ValueAdded: 4-112-1-83 LED status
2019-07-21T14:13:54.099Z z2m:Zwave ValueAdded: 4-112-1-84 Time set for Night light mode
2019-07-21T14:13:54.099Z z2m:Zwave ValueAdded: 4-112-1-86 Set the ON time of output load
2019-07-21T14:13:54.100Z z2m:Zwave ValueAdded: 4-112-1-87 Set the OFF time of output load
2019-07-21T14:13:54.100Z z2m:Zwave ValueAdded: 4-112-1-90 Enable/disable the parameter 91 and 92
2019-07-21T14:13:54.101Z z2m:Zwave ValueAdded: 4-112-1-91 Minimum Change to send Report (Watt)
2019-07-21T14:13:54.101Z z2m:Zwave ValueAdded: 4-112-1-92 Minimum Change to send Report (%)
2019-07-21T14:13:54.101Z z2m:Zwave ValueAdded: 4-112-1-100 Default Group Reports
2019-07-21T14:13:54.102Z z2m:Zwave ValueAdded: 4-112-1-101 Report type sent in Reporting Group 1
2019-07-21T14:13:54.102Z z2m:Zwave ValueAdded: 4-112-1-102 Report type sent in Reporting Group 2
2019-07-21T14:13:54.103Z z2m:Zwave ValueAdded: 4-112-1-103 Report type sent in Reporting Group 3
2019-07-21T14:13:54.103Z z2m:Zwave ValueAdded: 4-112-1-110 Set 111-113 to default
2019-07-21T14:13:54.104Z z2m:Zwave ValueAdded: 4-112-1-111 Send Interval for Reporting Group 1
2019-07-21T14:13:54.104Z z2m:Zwave ValueAdded: 4-112-1-112 Send Interval for Reporting Group 2
2019-07-21T14:13:54.105Z z2m:Zwave ValueAdded: 4-112-1-113 Send Interval for Reporting Group 3
2019-07-21T14:13:54.105Z z2m:Zwave ValueAdded: 4-112-1-120 Set the external S1 mode
2019-07-21T14:13:54.106Z z2m:Zwave ValueAdded: 4-112-1-121 Set the external S2 mode
2019-07-21T14:13:54.106Z z2m:Zwave ValueAdded: 4-112-1-122 Control destination for S1
2019-07-21T14:13:54.107Z z2m:Zwave ValueAdded: 4-112-1-123 Control destination for S2
2019-07-21T14:13:54.107Z z2m:Zwave ValueAdded: 4-112-1-144 WallSwipe
2019-07-21T14:13:54.107Z z2m:Zwave ValueAdded: 4-112-1-247 S1/S2 when the Switch mode is 3-way switch
2019-07-21T14:13:54.108Z z2m:Zwave ValueAdded: 4-112-1-248 Function of S1/S2
2019-07-21T14:13:54.108Z z2m:Zwave ValueAdded: 4-112-1-251 Configuration factory reset function
2019-07-21T14:13:54.109Z z2m:Zwave ValueAdded: 4-112-1-252 Configuration Locked
2019-07-21T14:13:54.109Z z2m:Zwave ValueAdded: 4-112-1-255 Reset To Factory Defaults
2019-07-21T14:13:54.110Z z2m:Zwave ValueAdded: 4-113-1-4 Heat
2019-07-21T14:13:54.110Z z2m:Zwave ValueAdded: 4-113-1-8 Power Management
2019-07-21T14:13:54.111Z z2m:Zwave ValueAdded: 4-113-1-256 Previous Event Cleared
2019-07-21T14:13:54.111Z z2m:Zwave ValueAdded: 4-114-1-0 Loaded Config Revision
2019-07-21T14:13:54.111Z z2m:Zwave ValueAdded: 4-114-1-1 Config File Revision
2019-07-21T14:13:54.112Z z2m:Zwave ValueAdded: 4-114-1-2 Latest Available Config File Revision
2019-07-21T14:13:54.112Z z2m:Zwave ValueAdded: 4-114-1-3 Device ID
2019-07-21T14:13:54.112Z z2m:Zwave ValueAdded: 4-114-1-4 Serial Number
2019-07-21T14:13:54.113Z z2m:Zwave ValueAdded: 4-115-1-0 Powerlevel
2019-07-21T14:13:54.113Z z2m:Zwave ValueAdded: 4-115-1-1 Timeout
2019-07-21T14:13:54.114Z z2m:Zwave ValueAdded: 4-115-1-2 Set Powerlevel
2019-07-21T14:13:54.114Z z2m:Zwave ValueAdded: 4-115-1-3 Test Node
2019-07-21T14:13:54.115Z z2m:Zwave ValueAdded: 4-115-1-4 Test Powerlevel
2019-07-21T14:13:54.115Z z2m:Zwave ValueAdded: 4-115-1-5 Frame Count
2019-07-21T14:13:54.116Z z2m:Zwave ValueAdded: 4-115-1-6 Test
2019-07-21T14:13:54.116Z z2m:Zwave ValueAdded: 4-115-1-7 Report
2019-07-21T14:13:54.116Z z2m:Zwave ValueAdded: 4-115-1-8 Test Status
2019-07-21T14:13:54.117Z z2m:Zwave ValueAdded: 4-115-1-9 Acked Frames
2019-07-21T14:13:54.117Z z2m:Zwave ValueAdded: 4-129-1-0 Day
2019-07-21T14:13:54.118Z z2m:Zwave ValueAdded: 4-129-1-1 Hour
2019-07-21T14:13:54.118Z z2m:Zwave ValueAdded: 4-129-1-2 Minute
2019-07-21T14:13:54.119Z z2m:Zwave ValueAdded: 4-134-1-0 Library Version
2019-07-21T14:13:54.122Z z2m:Zwave ValueAdded: 4-134-1-1 Protocol Version
2019-07-21T14:13:54.122Z z2m:Zwave ValueAdded: 4-134-1-2 Application Version
2019-07-21T14:13:54.228Z z2m:Zwave Notification from node 2: Notification - NoOperation (2)
2019-07-21T14:13:54.319Z z2m:Zwave Notification from node 4: Notification - NoOperation (2)
2019-07-21T14:13:54.331Z z2m:Zwave node 1 ready: Z-Wave.Me - ZME_UZB1 USB Stick (Static PC Controller)
2019-07-21T14:14:07.141Z z2m:Zwave node 2 ready: AEON Labs - ZW100 MultiSensor 6 (Home Security Sensor)
2019-07-21T14:14:07.346Z z2m:Zwave Notification from node 2: Notification - Node Asleep (4)
2019-07-21T14:14:07.371Z z2m:Zwave Success zwave api call setChangeVerified
2019-07-21T14:14:07.373Z z2m:Zwave node 4 ready: AEON Labs - ZW116 Nano Switch (On/Off Power Switch)
2019-07-21T14:14:07.375Z z2m:Zwave Network scan complete. Found: 3 nodes
2019-07-21T14:14:13.386Z z2m:App New connection NGfBViQ2WdOQumyQAAAA
2019-07-21T14:14:17.827Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-07-21T14:14:17.833Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:18.312Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true
2019-07-21T14:14:18.441Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:18.882Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:18.945Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:24.069Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-07-21T14:14:24.070Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:28.233Z z2m:Zwave Notification from node 4: Notification - TimeOut (1)
2019-07-21T14:14:28.363Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:29.014Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> false
2019-07-21T14:14:33.154Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-07-21T14:14:33.828Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-07-21T14:14:33.829Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:34.314Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true
2019-07-21T14:14:34.358Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:37.195Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:14:42.197Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-07-21T14:14:42.198Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:42.673Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> false
2019-07-21T14:14:42.924Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-07-21T14:14:47.044Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-07-21T14:14:49.540Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-07-21T14:14:49.541Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:14:49.783Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> false
2019-07-21T14:14:59.016Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:73.765 -> 73.765
2019-07-21T14:14:59.017Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-07-21T14:14:59.113Z z2m:Zwave zwave node 4: changed: 50-1-2:Electric - W:0 -> 73.46
2019-07-21T14:14:59.115Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-07-21T14:14:59.213Z z2m:Zwave zwave node 4: changed: 50-1-4:Electric - V:231.926 -> 232.301
2019-07-21T14:14:59.216Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-07-21T14:14:59.313Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.007 -> 0.437
2019-07-21T14:14:59.314Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-07-21T14:15:00.911Z z2m:Zwave Notification from node 4: Notification - TimeOut (1)
2019-07-21T14:15:01.327Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true
2019-07-21T14:15:01.412Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-21T14:15:01.854Z z2m:Zwave zwave node 2: changed: 49-1-1:Air Temperature:24 -> 24
2019-07-21T14:15:02.132Z z2m:Zwave zwave node 2: changed: 49-1-5:Humidity:56 -> 56
2019-07-21T14:15:03.049Z z2m:Zwave zwave node 2: changed: 128-1-0:Battery Level:100 -> 100
2019-07-21T14:15:03.254Z z2m:Zwave zwave node 2: changed: 49-1-3:Illuminance:0 -> 21
2019-07-21T14:15:03.492Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-07-21T14:15:10.912Z z2m:Zwave Notification from node 4: Notification - TimeOut (1)

sjorge commented 5 years ago

Meanwhile, I reverted my setup to a working version... here is the behavior with 'verify changes' set

2019-07-21T14:39:29.972Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-07-21T14:39:29.974Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:39:34.603Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> false
2019-07-21T14:39:40.568Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-07-21T14:39:40.568Z z2m:Zwave Success zwave api call setValue
2019-07-21T14:39:41.124Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true

I think the option might not be apply at all in 2.0.1-master

robertsLando commented 5 years ago

I think this is more likely a problem with openzwave 1.6 @sjorge . Should ask to @Fishwaldo

Fishwaldo commented 5 years ago

1.4 or 1.6?

sjorge commented 5 years ago

Works on 1.4, broken on 1.6

~ sjorge

On 23 Jul 2019, at 08:33, Justin Hammond notifications@github.com wrote:

1.4 or 1.6?

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

sjorge commented 5 years ago

From the OZW_Log it does seem to verify... so it might be a node-openzwave-shared issues?

2019-07-24 13:54:46.923 Info, Node004, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2019-07-24 13:54:46.923 Info, Node004, SwitchBinary::Set - Setting to Off
2019-07-24 13:54:46.923 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x2c, 0xcc
2019-07-24 13:54:46.923 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2d, 0xcc
2019-07-24 13:54:46.923 Detail,
2019-07-24 13:54:46.923 Info, Node004, Sending (Send) message (Callback ID=0x2c, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x2c, 0xcc
2019-07-24 13:54:46.923 Info, Node004, Encrypted Flag is 0
2019-07-24 13:54:46.931 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-07-24 13:54:46.931 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-07-24 13:54:46.955 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x2c, 0x00, 0x00, 0x03, 0x00, 0xae, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x00, 0x00, 0x00, 0x00, 0x02, 0x01, 0x00, 0x00, 0x75
2019-07-24 13:54:46.955 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x2c received (expected 0x2c)
2019-07-24 13:54:46.955 Info, Node004, Request RTT 31 Average Request RTT 31
2019-07-24 13:54:46.956 Detail, Node004, Extended TxStatus: Time: 30, Hops: 0, Rssi:  -82 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2019-07-24 13:54:46.956 Detail, Node004,   Expected callbackId was received
2019-07-24 13:54:46.956 Detail, Node004,   Expected reply was received
2019-07-24 13:54:46.956 Detail, Node004,   Message transaction complete
2019-07-24 13:54:46.956 Detail,
2019-07-24 13:54:46.956 Detail, Node004, Removing current message
2019-07-24 13:54:46.956 Detail,
2019-07-24 13:54:46.956 Info, Node004, Sending (Send) message (Callback ID=0x2d, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2d, 0xcc
2019-07-24 13:54:46.956 Info, Node004, Encrypted Flag is 0
2019-07-24 13:54:46.963 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-07-24 13:54:46.964 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-07-24 13:54:47.018 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x2d, 0x00, 0x00, 0x06, 0x00, 0xae, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x00, 0x00, 0x00, 0x00, 0x02, 0x01, 0x00, 0x00, 0x71
2019-07-24 13:54:47.019 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x2d received (expected 0x2d)
2019-07-24 13:54:47.019 Info, Node004, Request RTT 63 Average Request RTT 47
2019-07-24 13:54:47.019 Detail, Node004, Extended TxStatus: Time: 60, Hops: 0, Rssi:  -82 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2019-07-24 13:54:47.019 Detail, Node004,   Expected callbackId was received
2019-07-24 13:54:47.035 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0x00, 0xb1, 0x00, 0x60
2019-07-24 13:54:47.035 Detail,
2019-07-24 13:54:47.035 Info, Node004, Response RTT 80 Average Response RTT 65
2019-07-24 13:54:47.035 Info, Node004, Received SwitchBinary report from node 4: level=Off
2019-07-24 13:54:47.035 Detail, Node004, Refreshed Value: old value=true, new value=false, type=bool
2019-07-24 13:54:47.035 Detail, Node004, Changes to this value are verified
2019-07-24 13:54:47.035 Info, Node004, Changed value (possible)--rechecking
2019-07-24 13:54:47.035 Info, mgr,     Refreshing node 4: COMMAND_CLASS_SWITCH_BINARY index = 0 instance = 1 (to confirm a reported change)
2019-07-24 13:54:47.036 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2e, 0xcf
2019-07-24 13:54:47.036 Detail, Node004,   Expected reply and command class was received
2019-07-24 13:54:47.036 Detail, Node004,   Message transaction complete
2019-07-24 13:54:47.036 Detail,
2019-07-24 13:54:47.036 Detail, Node004, Removing current message
2019-07-24 13:54:47.036 Detail,
2019-07-24 13:54:47.036 Info, Node004, Sending (Send) message (Callback ID=0x2e, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2e, 0xcf
2019-07-24 13:54:47.036 Info, Node004, Encrypted Flag is 0
2019-07-24 13:54:47.043 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-07-24 13:54:47.043 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-07-24 13:54:47.068 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x2e, 0x00, 0x00, 0x03, 0x00, 0xb0, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x00, 0x00, 0x00, 0x00, 0x02, 0x01, 0x00, 0x00, 0x69
2019-07-24 13:54:47.068 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x2e received (expected 0x2e)
2019-07-24 13:54:47.068 Info, Node004, Request RTT 31 Average Request RTT 39
2019-07-24 13:54:47.068 Detail, Node004, Extended TxStatus: Time: 30, Hops: 0, Rssi:  -80 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2019-07-24 13:54:47.068 Detail, Node004,   Expected callbackId was received
2019-07-24 13:54:47.084 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0x00, 0xb1, 0x00, 0x60
2019-07-24 13:54:47.084 Detail,
2019-07-24 13:54:47.084 Info, Node004, Response RTT 47 Average Response RTT 56
2019-07-24 13:54:47.084 Info, Node004, Received SwitchBinary report from node 4: level=Off
2019-07-24 13:54:47.084 Detail, Node004, Refreshed Value: old value=true, new value=false, type=bool
2019-07-24 13:54:47.084 Detail, Node004, Changes to this value are verified
2019-07-24 13:54:47.084 Info, Node004, Changed value--confirmed
2019-07-24 13:54:47.084 Detail, Node004,   Expected reply and command class was received
2019-07-24 13:54:47.084 Detail, Node004,   Message transaction complete
2019-07-24 13:54:47.084 Detail,
2019-07-24 13:54:47.084 Detail, Node004, Removing current message
2019-07-24 13:54:47.084 Detail, Node004, Notification: ValueChanged

Or am I reading the log wrong?

robertsLando commented 5 years ago

Try to install openzwave-shared 1.4.8, if with that works so it is a problem with openzwave-shared

npm install openzwave-shared@1.4.8

Daniel

On 24 Jul 2019, at 16:08, Jorge Schrauwen notifications@github.com wrote:

From the OZW_Log it does seem to verify... so it might be a node-openzwave-shared issues?

2019-07-24 13:54:46.923 Info, Node004, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False 2019-07-24 13:54:46.923 Info, Node004, SwitchBinary::Set - Setting to Off 2019-07-24 13:54:46.923 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x2c, 0xcc 2019-07-24 13:54:46.923 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2d, 0xcc 2019-07-24 13:54:46.923 Detail, 2019-07-24 13:54:46.923 Info, Node004, Sending (Send) message (Callback ID=0x2c, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x2c, 0xcc 2019-07-24 13:54:46.923 Info, Node004, Encrypted Flag is 0 2019-07-24 13:54:46.931 Detail, Node004, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2019-07-24 13:54:46.931 Detail, Node004, ZW_SEND_DATA delivered to Z-Wave stack 2019-07-24 13:54:46.955 Detail, Node004, Received: 0x01, 0x18, 0x00, 0x13, 0x2c, 0x00, 0x00, 0x03, 0x00, 0xae, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x00, 0x00, 0x00, 0x00, 0x02, 0x01, 0x00, 0x00, 0x75 2019-07-24 13:54:46.955 Detail, Node004, ZW_SEND_DATA Request with callback ID 0x2c received (expected 0x2c) 2019-07-24 13:54:46.955 Info, Node004, Request RTT 31 Average Request RTT 31 2019-07-24 13:54:46.956 Detail, Node004, Extended TxStatus: Time: 30, Hops: 0, Rssi: -82 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0 2019-07-24 13:54:46.956 Detail, Node004, Expected callbackId was received 2019-07-24 13:54:46.956 Detail, Node004, Expected reply was received 2019-07-24 13:54:46.956 Detail, Node004, Message transaction complete 2019-07-24 13:54:46.956 Detail, 2019-07-24 13:54:46.956 Detail, Node004, Removing current message 2019-07-24 13:54:46.956 Detail, 2019-07-24 13:54:46.956 Info, Node004, Sending (Send) message (Callback ID=0x2d, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2d, 0xcc 2019-07-24 13:54:46.956 Info, Node004, Encrypted Flag is 0 2019-07-24 13:54:46.963 Detail, Node004, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2019-07-24 13:54:46.964 Detail, Node004, ZW_SEND_DATA delivered to Z-Wave stack 2019-07-24 13:54:47.018 Detail, Node004, Received: 0x01, 0x18, 0x00, 0x13, 0x2d, 0x00, 0x00, 0x06, 0x00, 0xae, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x00, 0x00, 0x00, 0x00, 0x02, 0x01, 0x00, 0x00, 0x71 2019-07-24 13:54:47.019 Detail, Node004, ZW_SEND_DATA Request with callback ID 0x2d received (expected 0x2d) 2019-07-24 13:54:47.019 Info, Node004, Request RTT 63 Average Request RTT 47 2019-07-24 13:54:47.019 Detail, Node004, Extended TxStatus: Time: 60, Hops: 0, Rssi: -82 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0 2019-07-24 13:54:47.019 Detail, Node004, Expected callbackId was received 2019-07-24 13:54:47.035 Detail, Node004, Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0x00, 0xb1, 0x00, 0x60 2019-07-24 13:54:47.035 Detail, 2019-07-24 13:54:47.035 Info, Node004, Response RTT 80 Average Response RTT 65 2019-07-24 13:54:47.035 Info, Node004, Received SwitchBinary report from node 4: level=Off 2019-07-24 13:54:47.035 Detail, Node004, Refreshed Value: old value=true, new value=false, type=bool 2019-07-24 13:54:47.035 Detail, Node004, Changes to this value are verified 2019-07-24 13:54:47.035 Info, Node004, Changed value (possible)--rechecking 2019-07-24 13:54:47.035 Info, mgr, Refreshing node 4: COMMAND_CLASS_SWITCH_BINARY index = 0 instance = 1 (to confirm a reported change) 2019-07-24 13:54:47.036 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2e, 0xcf 2019-07-24 13:54:47.036 Detail, Node004, Expected reply and command class was received 2019-07-24 13:54:47.036 Detail, Node004, Message transaction complete 2019-07-24 13:54:47.036 Detail, 2019-07-24 13:54:47.036 Detail, Node004, Removing current message 2019-07-24 13:54:47.036 Detail, 2019-07-24 13:54:47.036 Info, Node004, Sending (Send) message (Callback ID=0x2e, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x2e, 0xcf 2019-07-24 13:54:47.036 Info, Node004, Encrypted Flag is 0 2019-07-24 13:54:47.043 Detail, Node004, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2019-07-24 13:54:47.043 Detail, Node004, ZW_SEND_DATA delivered to Z-Wave stack 2019-07-24 13:54:47.068 Detail, Node004, Received: 0x01, 0x18, 0x00, 0x13, 0x2e, 0x00, 0x00, 0x03, 0x00, 0xb0, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x00, 0x00, 0x00, 0x00, 0x02, 0x01, 0x00, 0x00, 0x69 2019-07-24 13:54:47.068 Detail, Node004, ZW_SEND_DATA Request with callback ID 0x2e received (expected 0x2e) 2019-07-24 13:54:47.068 Info, Node004, Request RTT 31 Average Request RTT 39 2019-07-24 13:54:47.068 Detail, Node004, Extended TxStatus: Time: 30, Hops: 0, Rssi: -80 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0 2019-07-24 13:54:47.068 Detail, Node004, Expected callbackId was received 2019-07-24 13:54:47.084 Detail, Node004, Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0x00, 0xb1, 0x00, 0x60 2019-07-24 13:54:47.084 Detail, 2019-07-24 13:54:47.084 Info, Node004, Response RTT 47 Average Response RTT 56 2019-07-24 13:54:47.084 Info, Node004, Received SwitchBinary report from node 4: level=Off 2019-07-24 13:54:47.084 Detail, Node004, Refreshed Value: old value=true, new value=false, type=bool 2019-07-24 13:54:47.084 Detail, Node004, Changes to this value are verified 2019-07-24 13:54:47.084 Info, Node004, Changed value--confirmed 2019-07-24 13:54:47.084 Detail, Node004, Expected reply and command class was received 2019-07-24 13:54:47.084 Detail, Node004, Message transaction complete 2019-07-24 13:54:47.084 Detail, 2019-07-24 13:54:47.084 Detail, Node004, Removing current message 2019-07-24 13:54:47.084 Detail, Node004, Notification: ValueChanged Or am I reading the log wrong?

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

sjorge commented 5 years ago

That made no difference.

I'll try:

zwave2mqtt 2.0.2
openzwave-shared 1.4.8
libopenzwave 1.4

Before I head off to bed.

sjorge commented 5 years ago

That combination also doesn't work :/

2019-07-24T14:49:35.453Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-07-24 14:49:35.454 Info, Node004, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2019-07-24 14:49:35.454 Info, Node004, SwitchBinary::Set - Setting node 4 to Off
2019-07-24 14:49:35.454 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x38, 0xd8
2019-07-24 14:49:35.454 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x39, 0xd8
2019-07-24 14:49:35.454 Detail,
2019-07-24T14:49:35.454Z z2m:Zwave Success zwave api call setValue
2019-07-24 14:49:35.454 Info, Node004, Sending (Send) message (Callback ID=0x38, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x38, 0xd8
2019-07-24 14:49:35.462 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-07-24 14:49:35.462 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-07-24 14:49:35.486 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x38, 0x00, 0x00, 0x02, 0x00, 0xab, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x00, 0x00, 0x00, 0x00, 0x02, 0x01, 0x00, 0x00, 0x65
2019-07-24 14:49:35.486 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x38 received (expected 0x38)
2019-07-24 14:49:35.486 Info, Node004, Request RTT 31 Average Request RTT 34
2019-07-24 14:49:35.486 Detail,   Expected callbackId was received
2019-07-24 14:49:35.486 Detail,   Expected reply was received
2019-07-24 14:49:35.486 Detail,   Message transaction complete
2019-07-24 14:49:35.486 Detail,
2019-07-24 14:49:35.486 Detail, Node004, Removing current message
2019-07-24 14:49:35.486 Detail,
2019-07-24 14:49:35.487 Info, Node004, Sending (Send) message (Callback ID=0x39, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x39, 0xd8
2019-07-24 14:49:35.494 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-07-24 14:49:35.494 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-07-24 14:49:35.518 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x39, 0x00, 0x00, 0x03, 0x00, 0xad, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x00, 0x00, 0x00, 0x00, 0x02, 0x01, 0x00, 0x00, 0x63
2019-07-24 14:49:35.519 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x39 received (expected 0x39)
2019-07-24 14:49:35.519 Info, Node004, Request RTT 32 Average Request RTT 33
2019-07-24 14:49:35.519 Detail,   Expected callbackId was received
2019-07-24 14:49:35.575 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0xff, 0xad, 0x00, 0x83
2019-07-24 14:49:35.575 Detail,
2019-07-24 14:49:35.575 Info, Node004, Response RTT 88 Average Response RTT 72
2019-07-24 14:49:35.575 Info, Node004, Received SwitchBinary report from node 4: level=On
2019-07-24 14:49:35.575 Detail, Node004, Refreshed Value: old value=true, new value=true, type=bool
2019-07-24 14:49:35.575 Detail, Node004, Changes to this value are verified
2019-07-24 14:49:35.575 Detail, Node004,   Expected reply and command class was received
2019-07-24 14:49:35.575 Detail, Node004,   Message transaction complete
2019-07-24 14:49:35.575 Detail,
2019-07-24 14:49:35.575 Detail, Node004, Removing current message
2019-07-24 14:49:35.575 Detail, Node004, Notification: ValueRefreshed
2019-07-24T14:49:35.576Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true
2019-07-24 14:49:35.629 Detail, Node004,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x04, 0x03, 0x25, 0x03, 0xff, 0xae, 0x00, 0x80
2019-07-24 14:49:35.629 Detail,
2019-07-24 14:49:35.629 Info, Node004, Received SwitchBinary report from node 4: level=On
2019-07-24 14:49:35.629 Detail, Node004, Refreshed Value: old value=true, new value=true, type=bool
2019-07-24 14:49:35.629 Detail, Node004, Changes to this value are verified
2019-07-24 14:49:35.629 Detail, Node004, Notification: ValueRefreshed
2019-07-24T14:49:35.629Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> true

sjorge commented 5 years ago

This is using:

zwave2mqtt 1.0.3
node-openzwave-shared 1.4.8
libopenzwave 1.4.3448

2019-07-24T15:02:59.338Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-07-24T15:02:59.339Z z2m:Zwave Success zwave api call setValue
2019-07-24T15:02:59.779Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true
2019-07-24T15:03:08.338Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-07-24T15:03:08.338Z z2m:Zwave Success zwave api call setValue
2019-07-24T15:03:08.809Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:true -> false
2019-07-24T15:03:12.657Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-07-24T15:03:12.658Z z2m:Zwave Success zwave api call setValue
2019-07-24T15:03:13.119Z z2m:Zwave zwave node 4: changed: 37-1-0:Switch:false -> true

sjorge commented 5 years ago

I forgot to enable debug logging for this one, but it's working fine here, we're only getting one valuechange after a short delay that is the correct change.

sjorge commented 5 years ago

@robertsLando is it still likely a openzwave bug? Given that zwave2mqtt 1.0.3 is working while 2.0.1 is not with the same node-bindings for ozw 1.4

robertsLando commented 5 years ago

I need to check out the code but I don’t remember to have changed that.

Daniel

On 27 Jul 2019, at 22:41, Jorge Schrauwen notifications@github.com wrote:

@robertsLando is it still likely a openzwave bug? Given that zwave2mqtt 1.0.3 is working while 2.0.1 is not with the same node-bindings for ozw 1.4

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

robertsLando commented 5 years ago

I do it there:

https://github.com/OpenZWave/Zwave2Mqtt/blob/master/lib/Gateway.js#L189

That is triggered here:

https://github.com/OpenZWave/Zwave2Mqtt/blob/master/lib/ZwaveClient.js#L327

Does your nodes are battery powered devices?

If so maybe they never become ready and this never get triggered

Daniel

On 27 Jul 2019, at 22:41, Jorge Schrauwen notifications@github.com wrote:

@robertsLando is it still likely a openzwave bug? Given that zwave2mqtt 1.0.3 is working while 2.0.1 is not with the same node-bindings for ozw 1.4

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

sjorge commented 5 years ago

No, that is the non battery powered node that never goes to sleep... maybe the node is in an 'unknown' state at startup so the changes never get applied?

robertsLando commented 5 years ago

Check from logs if you se “node xx ready” if so that us triggered

Daniel

On 29 Jul 2019, at 19:44, Jorge Schrauwen notifications@github.com wrote:

No, that is the non battery powered node that never goes to sleep... maybe the node is in an 'unknown' state at startup so the changes never get applied?

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

sjorge commented 5 years ago

I had some time to play with it again, I update to master, do the npm install and friends dance and enabled debug logging. Below is a log from cold startup, were I let it settle for a bit and then toggle the switch. There was no response at all this time :/

[ Aug  3 10:12:03 Enabled. ]
[ Aug  3 10:12:03 Executing start method ("/opt/local/bin/node /opt/zwave2mqtt/bin/www"). ]
2019-08-03T10:12:03.943Z z2m:Store scenes.json not found
2019-08-03T10:12:04.332Z z2m:App Application path:/opt/zwave2mqtt_16
Initialising OpenZWave 1.6.878 binary addon for Node.JS.
    OpenZWave Security API is ENABLED
    ZWave device db    : /opt/local/etc/openzwave
    User settings path : /opt/zwave2mqtt_16/store
    Option Overrides : --Logging true --ConsoleOutput true --QueueLogLevel 8 --DriverMaxAttempts 9999 --SaveConfiguration true --PollInterval 60000 --NetworkKey 0x58,0x50,0x3A,0x1C,0x0A,0xD7,0x66,0x6D,0x27,0x78,0x67,0x4C,0x1E,0xCB,0xC4,0x61
2019-08-03T10:12:04.424Z z2m:Zwave Connecting to /dev/cua/0
2019-08-03 10:12:04.425 Always, OpenZwave Version 1.6-878-g707e6b4e-dirty Starting Up
2019-08-03 10:12:04.433 Info, Loading Localization File /opt/local/etc/openzwave/Localization.xml
2019-08-03 10:12:04.436 Info, Loaded /opt/local/etc/openzwave/Localization.xml With Revision 8
2019-08-03 10:12:04.436 Always, Using Language Localization
2019-08-03 10:12:04.437 Info, Loading NotificationCCTypes File /opt/local/etc/openzwave/NotificationCCTypes.xml
2019-08-03 10:12:04.437 Info, Loaded /opt/local/etc/openzwave/NotificationCCTypes.xml With Revision 9
2019-08-03 10:12:04.438 Info, Loading SensorMultiLevelCCTypes File /opt/local/etc/openzwave/SensorMultiLevelCCTypes.xml
2019-08-03 10:12:04.438 Info, Loaded /opt/local/etc/openzwave/SensorMultiLevelCCTypes.xml With Revision 3
2019-08-03 10:12:04.439 Info, Setting Up Provided Network Key for Secure Communications
2019-08-03 10:12:04.449 Info, Manufacturer_Specific.xml file Revision is 44
2019-08-03 10:12:04.586 Info, Product name collision: FGR223 Roller Shutter Controller 3 type 303 id 1000 manufacturerid 10f, collides with FGRM223 Roller Shutter Controller 3, type 303 id 1000 manufacturerid 10f
2019-08-03 10:12:04.670 Info, Product name collision: ED2.0 Meter Adapter type 0 id 0 manufacturerid 128, collides with ED2.0 Meter Adapter, type 0 id 0 manufacturerid 128
2019-08-03 10:12:04.670 Info, Product name collision: ED2.0 Display type 128 id 0 manufacturerid 128, collides with ED2.0 Display, type 128 id 0 manufacturerid 128
2019-08-03 10:12:04.715 Info, Product name collision: GR105 Auto Valve type 202 id 511 manufacturerid 152, collides with GR105 Auto Valve, type 202 id 511 manufacturerid 152
2019-08-03 10:12:04.715 Info, Product name collision: GR105n Auto Valve type 3 id 512 manufacturerid 152, collides with GR105n Auto Valve, type 3 id 512 manufacturerid 152
2019-08-03 10:12:04.715 Info, Product name collision: ZSE02 Motion Sensor type 500 id 3 manufacturerid 152, collides with ZSE02 Motion Sensor, type 500 id 3 manufacturerid 152
2019-08-03 10:12:04.722 Info, Product name collision: ZWN-BPC Ceiling Mounted PIR Motion Sensor type 601 id 901 manufacturerid 11a, collides with ZWN-BPC Ceiling Mounted PIR Motion Sensor, type 601 id 901 manufacturerid 11a
2019-08-03 10:12:04.722 Info, Product name collision: ZWN-SC7 7-Button Scene Controller type 801 id b03 manufacturerid 11a, collides with ZWN-SC7 7-Button Scene Controller, type 801 id b03 manufacturerid 11a
2019-08-03 10:12:04.722 Info, Product name collision: ZW15S 15A On/Off Switch type 101 id 102 manufacturerid 11a, collides with ZW15S 15A On/Off Switch, type 101 id 102 manufacturerid 11a
2019-08-03 10:12:04.722 Info, Product name collision: ZW20R 20A TR Duplex Receptacle type 101 id 603 manufacturerid 11a, collides with ZW20R 20A TR Duplex Receptacle, type 101 id 603 manufacturerid 11a
2019-08-03 10:12:04.722 Info, Product name collision: ZWN-RSM1 PLUS-Smart Single Relay Switch Module type 111 id 605 manufacturerid 11a, collides with ZWN-RSM1 PLUS—Smart Single Relay Switch Module, type 111 id 605 manufacturerid 11a
2019-08-03 10:12:04.722 Info, Product name collision: ZW20RM 20A TR Smart Meter Duplex Receptacle type 111 id 101 manufacturerid 11a, collides with ZW20RM 20A TR Smart Meter Duplex Receptacle, type 111 id 101 manufacturerid 11a
2019-08-03 10:12:04.722 Info, Product name collision: ZW500D 500W In-Wall Preset Dimmer Switch type 102 id 201 manufacturerid 11a, collides with ZW500D 500W In-Wall Preset Dimmer Switch, type 102 id 201 manufacturerid 11a
2019-08-03 10:12:04.722 Info, Product name collision: ZW15RM Plus 15A TR Smart Meter Duplex Receptacle type 111 id 105 manufacturerid 11a, collides with ZW15RM Plus 15A TR Smart Meter Duplex Receptacle, type 111 id 105 manufacturerid 11a
2019-08-03 10:12:04.739 Info, Queuing Lookup on mfs.db.openzwave.com for Node 0
2019-08-03 10:12:04.739 Info,   Opening controller /dev/cua/0
2019-08-03 10:12:04.739 Info, Trying to open serial port /dev/cua/0 (attempt 1)
2019-08-03 10:12:04.739 Info, mgr,     Added driver for controller /dev/cua/0
2019-08-03 10:12:04.739 Info, Timer: thread starting
2019-08-03 10:12:04.739 Detail, Timer: waiting with timeout -1 ms
2019-08-03 10:12:04.739 Info, Starting DNSThread
2019-08-03 10:12:04.740 Info, LookupTxT Checking mfs.db.openzwave.com
2019-08-03 10:12:04.741 Info, Serial port /dev/cua/0 opened (attempt 1)
2019-08-03 10:12:04.741 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-08-03 10:12:04.742 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-08-03 10:12:04.742 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-08-03 10:12:04.742 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-08-03 10:12:04.742 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-08-03 10:12:04.753 Info, Lookup for mfs.db.openzwave.com returned 44
2019-08-03 10:12:04.756 Info, ManufacturerSpecificDB Initialized
2019-08-03 10:12:04.756 Detail, Notification: ManufacturerSpecificDB Ready
2019-08-03 10:12:04.756 Detail,
2019-08-03 10:12:04.756 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2019-08-03 10:12:04.756 Info, contrlr, Encrypted Flag is 0
2019-08-03 10:12:04.758 Detail, contrlr,   Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x36, 0x2e, 0x30, 0x32, 0x00, 0x01, 0x92
2019-08-03 10:12:04.758 Detail,
2019-08-03 10:12:04.758 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2019-08-03 10:12:04.759 Info, contrlr,     Static Controller library, version Z-Wave 6.02
2019-08-03 10:12:04.759 Detail, contrlr,   Expected reply was received
2019-08-03 10:12:04.759 Detail, contrlr,   Message transaction complete
2019-08-03 10:12:04.759 Detail,
2019-08-03 10:12:04.759 Detail, contrlr, Removing current message
2019-08-03 10:12:04.759 Detail,
2019-08-03 10:12:04.759 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2019-08-03 10:12:04.759 Info, contrlr, Encrypted Flag is 0
2019-08-03 10:12:04.761 Detail, contrlr,   Received: 0x01, 0x08, 0x01, 0x20, 0xfc, 0x20, 0xd0, 0x90, 0x01, 0x4b
2019-08-03 10:12:04.761 Detail,
2019-08-03 10:12:04.761 Info, contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. Home ID = 0xfc20d090.  Our node ID = 1
2019-08-03 10:12:04.761 Detail, contrlr,   Expected reply was received
2019-08-03 10:12:04.761 Detail, contrlr,   Message transaction complete
2019-08-03 10:12:04.761 Detail,
2019-08-03 10:12:04.761 Detail, contrlr, Removing current message
2019-08-03 10:12:04.761 Detail,
2019-08-03 10:12:04.761 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2019-08-03 10:12:04.761 Info, contrlr, Encrypted Flag is 0
2019-08-03 10:12:04.763 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x05, 0x1c, 0xe3
2019-08-03 10:12:04.763 Detail,
2019-08-03 10:12:04.763 Info, contrlr, Received reply to FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2019-08-03 10:12:04.763 Info, contrlr,     There is a SUC ID Server (SIS) in this network.
2019-08-03 10:12:04.763 Info, contrlr,     The PC controller is an inclusion static update controller (SUC) and was the original primary before the SIS was added.
2019-08-03 10:12:04.763 Detail, contrlr,   Expected reply was received
2019-08-03 10:12:04.763 Detail, contrlr,   Message transaction complete
2019-08-03 10:12:04.763 Detail,
2019-08-03 10:12:04.763 Detail, contrlr, Removing current message
2019-08-03 10:12:04.763 Detail,
2019-08-03 10:12:04.763 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2019-08-03 10:12:04.763 Info, contrlr, Encrypted Flag is 0
2019-08-03 10:12:04.767 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-08-03 10:12:04.767 Detail,
2019-08-03 10:12:04.767 Info, contrlr,  Received reply to FUNC_ID_SERIAL_API_GET_CAPABILITIES
2019-08-03 10:12:04.767 Info, contrlr,     Serial API Version:   5.36
2019-08-03 10:12:04.767 Info, contrlr,     Manufacturer ID:      0x0115
2019-08-03 10:12:04.767 Info, contrlr,     Product Type:         0x0400
2019-08-03 10:12:04.767 Info, contrlr,     Product ID:           0x0001
2019-08-03 10:12:04.767 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-08-03 10:12:04.767 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SETUP: 0x01, 0x05, 0x00, 0x0b, 0x02, 0x01, 0xf2
2019-08-03 10:12:04.767 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-08-03 10:12:04.767 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2019-08-03 10:12:04.768 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x08, 0x00, 0x03, 0x01, 0x02, 0x01, 0x01, 0x5e, 0xa9
2019-08-03 10:12:04.768 Detail, contrlr,   Expected reply was received
2019-08-03 10:12:04.768 Detail, contrlr,   Message transaction complete
2019-08-03 10:12:04.768 Detail,
2019-08-03 10:12:04.768 Detail, contrlr, Removing current message
2019-08-03 10:12:04.768 Detail,
2019-08-03 10:12:04.768 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2019-08-03 10:12:04.768 Info, contrlr, Encrypted Flag is 0
2019-08-03 10:12:04.770 Detail, contrlr,   Received: 0x01, 0x04, 0x01, 0x56, 0x01, 0xad
2019-08-03 10:12:04.770 Detail,
2019-08-03 10:12:04.770 Info, contrlr, Received reply to GET_SUC_NODE_ID.  Node ID = 1
2019-08-03 10:12:04.770 Detail, contrlr,   Expected reply was received
2019-08-03 10:12:04.770 Detail, contrlr,   Message transaction complete
2019-08-03 10:12:04.770 Detail,
2019-08-03 10:12:04.770 Detail, contrlr, Removing current message
2019-08-03 10:12:04.770 Detail,
2019-08-03 10:12:04.770 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2019-08-03 10:12:04.770 Info, contrlr, Encrypted Flag is 0
2019-08-03 10:12:04.827 Detail, contrlr,   Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 0x01, 0xb9, 0x88, 0xab, 0x12, 0x79, 0x45, 0xb5, 0xb4, 0x51, 0x24, 0x9c, 0xd8, 0x89, 0xf8, 0x4f, 0xdc, 0x4f, 0xf6, 0xf5, 0x1a, 0xe2, 0x6e, 0xca, 0x1f, 0x3c, 0x9a, 0xfe, 0xb2, 0xd4, 0x9b, 0x22, 0x09
2019-08-03 10:12:04.827 Detail,
2019-08-03 10:12:04.827 Info, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2019-08-03 10:12:04.827 Detail, contrlr,   Expected reply was received
2019-08-03 10:12:04.827 Detail, contrlr,   Message transaction complete
2019-08-03 10:12:04.827 Detail,
2019-08-03 10:12:04.827 Detail, contrlr, Removing current message
2019-08-03 10:12:04.827 Detail,
2019-08-03 10:12:04.827 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x0b) - FUNC_ID_SERIAL_API_SETUP: 0x01, 0x05, 0x00, 0x0b, 0x02, 0x01, 0xf2
2019-08-03 10:12:04.827 Info, contrlr, Encrypted Flag is 0
2019-08-03 10:12:04.829 Detail, contrlr,   Received: 0x01, 0x05, 0x01, 0x0b, 0x02, 0x01, 0xf3
2019-08-03 10:12:04.829 Detail,
2019-08-03 10:12:04.829 Info, Received reply to FUNC_ID_SERIAL_API_SETUP
2019-08-03 10:12:04.829 Info, Successfully enabled extended txStatusReport.
2019-08-03 10:12:04.829 Detail, contrlr,   Expected reply was received
2019-08-03 10:12:04.829 Detail, contrlr,   Message transaction complete
2019-08-03 10:12:04.829 Detail,
2019-08-03 10:12:04.829 Detail, contrlr, Removing current message
2019-08-03 10:12:04.829 Detail,
2019-08-03 10:12:04.830 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2019-08-03 10:12:04.830 Info, contrlr, Encrypted Flag is 0
2019-08-03T10:12:04.829Z z2m:App Listening on port 8091
2019-08-03T10:12:04.891Z z2m:Mqtt MQTT client connected
2019-08-03 10:12:04.927 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-08-03 10:12:04.927 Detail,
2019-08-03 10:12:04.927 Info, mgr,     Driver with Home ID of 0xfc20d090 is now ready.
2019-08-03 10:12:04.927 Info,
2019-08-03 10:12:04.936 Info, Node001, (32 - COMMAND_CLASS_BASIC) - Compatibility Flags:
2019-08-03 10:12:04.936 Info, Node001, (32 - COMMAND_CLASS_BASIC) - State Flags:
2019-08-03 10:12:04.936 Info, Node001,   AfterMark: true
2019-08-03 10:12:04.936 Info, Node001, (114 - COMMAND_CLASS_MANUFACTURER_SPECIFIC) - Compatibility Flags:
2019-08-03 10:12:04.936 Info, Node001, (114 - COMMAND_CLASS_MANUFACTURER_SPECIFIC) - State Flags:
2019-08-03 10:12:04.936 Info, Node001,   StaticRequests: 2
2019-08-03 10:12:04.939 Info, Node002, (32 - COMMAND_CLASS_BASIC) - Compatibility Flags:
2019-08-03 10:12:04.939 Info, Node002,   Mapping: 49
2019-08-03 10:12:04.939 Info, Node002, (32 - COMMAND_CLASS_BASIC) - State Flags:
2019-08-03 10:12:04.939 Info, Node002,   AfterMark: true
2019-08-03 10:12:04.940 Info, Node002,   StaticRequests: 4
2019-08-03 10:12:04.940 Info, Node002, (48 - COMMAND_CLASS_SENSOR_BINARY) - Compatibility Flags:
2019-08-03 10:12:04.940 Info, Node002, (48 - COMMAND_CLASS_SENSOR_BINARY) - State Flags:
2019-08-03 10:12:04.940 Info, Node002,   InNif: true
2019-08-03 10:12:04.940 Info, Node002,   StaticRequests: 4
2019-08-03 10:12:04.940 Info, Node002, (49 - COMMAND_CLASS_SENSOR_MULTILEVEL) - Compatibility Flags:
2019-08-03 10:12:04.940 Info, Node002, (49 - COMMAND_CLASS_SENSOR_MULTILEVEL) - State Flags:
2019-08-03 10:12:04.940 Info, Node002,   CCVersion: 5
2019-08-03 10:12:04.940 Info, Node002,   InNif: true
2019-08-03 10:12:04.940 Info, Node002, (90 - COMMAND_CLASS_DEVICE_RESET_LOCALLY) - Compatibility Flags:
2019-08-03 10:12:04.940 Info, Node002, (90 - COMMAND_CLASS_DEVICE_RESET_LOCALLY) - State Flags:
2019-08-03 10:12:04.940 Info, Node002,   InNif: true
2019-08-03 10:12:04.940 Info, Node002,   StaticRequests: 4
2019-08-03 10:12:04.940 Info, Node002, (94 - COMMAND_CLASS_ZWAVEPLUS_INFO) - Compatibility Flags:
2019-08-03 10:12:04.940 Info, Node002, (94 - COMMAND_CLASS_ZWAVEPLUS_INFO) - State Flags:
2019-08-03 10:12:04.940 Info, Node002,   InNif: true
2019-08-03 10:12:04.940 Info, Node002,   StaticRequests: 6
2019-08-03 10:12:04.940 Info, Node002, (112 - COMMAND_CLASS_CONFIGURATION) - Compatibility Flags:
2019-08-03 10:12:04.940 Info, Node002, (112 - COMMAND_CLASS_CONFIGURATION) - State Flags:
2019-08-03 10:12:04.940 Info, Node002,   InNif: true
2019-08-03 10:12:04.940 Info, Node002,   StaticRequests: 4
2019-08-03 10:12:04.941 Info, Node002, (113 - COMMAND_CLASS_NOTIFICATION) - Compatibility Flags:
2019-08-03 10:12:04.941 Info, Node002,   GetSupported: false
2019-08-03 10:12:04.941 Info, Node002, (113 - COMMAND_CLASS_NOTIFICATION) - State Flags:
2019-08-03 10:12:04.941 Info, Node002,   CCVersion: 3
2019-08-03 10:12:04.941 Info, Node002,   InNif: true
2019-08-03 10:12:04.941 Info, Node002,   StaticRequests: 0
2019-08-03 10:12:04.941 Warning, Value size is invalid (0). Only 1, 2 & 4 supported for node 2, class 0x71, instance 1, index 7
2019-08-03 10:12:04.941 Info, Node002, (114 - COMMAND_CLASS_MANUFACTURER_SPECIFIC) - Compatibility Flags:
2019-08-03 10:12:04.941 Info, Node002, (114 - COMMAND_CLASS_MANUFACTURER_SPECIFIC) - State Flags:
2019-08-03 10:12:04.941 Info, Node002,   CCVersion: 2
2019-08-03 10:12:04.941 Info, Node002,   InNif: true
2019-08-03 10:12:04.941 Info, Node002,   StaticRequests: 0
2019-08-03 10:12:04.942 Info, Node002, (115 - COMMAND_CLASS_POWERLEVEL) - Compatibility Flags:
2019-08-03 10:12:04.942 Info, Node002, (115 - COMMAND_CLASS_POWERLEVEL) - State Flags:
2019-08-03 10:12:04.942 Info, Node002,   InNif: true
2019-08-03 10:12:04.942 Info, Node002,   StaticRequests: 4
2019-08-03 10:12:04.942 Info, Node002, (128 - COMMAND_CLASS_BATTERY) - Compatibility Flags:
2019-08-03 10:12:04.942 Info, Node002, (128 - COMMAND_CLASS_BATTERY) - State Flags:
2019-08-03 10:12:04.942 Info, Node002,   InNif: true
2019-08-03 10:12:04.942 Info, Node002,   StaticRequests: 4
2019-08-03 10:12:04.942 Info, Node002, (132 - COMMAND_CLASS_WAKE_UP) - Compatibility Flags:
2019-08-03 10:12:04.942 Info, Node002, (132 - COMMAND_CLASS_WAKE_UP) - State Flags:
2019-08-03 10:12:04.942 Info, Node002,   CCVersion: 2
2019-08-03 10:12:04.942 Info, Node002,   InNif: true
2019-08-03 10:12:04.942 Info, Node002,   StaticRequests: 0
2019-08-03 10:12:04.942 Info, Node002, (133 - COMMAND_CLASS_ASSOCIATION) - Compatibility Flags:
2019-08-03 10:12:04.942 Info, Node002, (133 - COMMAND_CLASS_ASSOCIATION) - State Flags:
2019-08-03 10:12:04.942 Info, Node002,   InNif: true
2019-08-03 10:12:04.942 Info, Node002,   StaticRequests: 4
2019-08-03 10:12:04.942 Info, Node002, (134 - COMMAND_CLASS_VERSION) - Compatibility Flags:
2019-08-03 10:12:04.942 Info, Node002, (134 - COMMAND_CLASS_VERSION) - State Flags:
2019-08-03 10:12:04.942 Info, Node002,   InNif: true
2019-08-03 10:12:04.943 Info, Node002,   StaticRequests: 4
2019-08-03 10:12:04.944 Info, Node004, (32 - COMMAND_CLASS_BASIC) - Compatibility Flags:
2019-08-03 10:12:04.945 Info, Node004,   Mapping: 37
2019-08-03 10:12:04.945 Info, Node004, (32 - COMMAND_CLASS_BASIC) - State Flags:
2019-08-03 10:12:04.945 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.945 Info, Node004, (37 - COMMAND_CLASS_SWITCH_BINARY) - Compatibility Flags:
2019-08-03 10:12:04.945 Info, Node004, (37 - COMMAND_CLASS_SWITCH_BINARY) - State Flags:
2019-08-03 10:12:04.945 Info, Node004,   InNif: true
2019-08-03 10:12:04.945 Info, Node004, (39 - COMMAND_CLASS_SWITCH_ALL) - Compatibility Flags:
2019-08-03 10:12:04.945 Info, Node004, (39 - COMMAND_CLASS_SWITCH_ALL) - State Flags:
2019-08-03 10:12:04.945 Info, Node004,   InNif: true
2019-08-03 10:12:04.945 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.945 Info, Node004, (43 - COMMAND_CLASS_SCENE_ACTIVATION) - Compatibility Flags:
2019-08-03 10:12:04.945 Info, Node004, (43 - COMMAND_CLASS_SCENE_ACTIVATION) - State Flags:
2019-08-03 10:12:04.945 Info, Node004,   InNif: true
2019-08-03 10:12:04.945 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.945 Warning, Localization::ReadXMLVIDHelp: Error in /opt/zwave2mqtt_16/store/ozwcache_0xfc20d090.xml at line 616 - No Help Entry for CommandClass 43, ValueID: 0 (-1):  (null) (Lang: )
2019-08-03 10:12:04.945 Warning, Localization::ReadXMLVIDHelp: Error in /opt/zwave2mqtt_16/store/ozwcache_0xfc20d090.xml at line 619 - No Help Entry for CommandClass 43, ValueID: 1 (-1):  (null) (Lang: )
2019-08-03 10:12:04.945 Info, Node004, (50 - COMMAND_CLASS_METER) - Compatibility Flags:
2019-08-03 10:12:04.945 Info, Node004, (50 - COMMAND_CLASS_METER) - State Flags:
2019-08-03 10:12:04.945 Info, Node004,   CCVersion: 3
2019-08-03 10:12:04.945 Info, Node004,   InNif: true
2019-08-03 10:12:04.945 Info, Node004,   StaticRequests: 2
2019-08-03 10:12:04.945 Warning, Localization::ReadXMLVIDHelp: Error in /opt/zwave2mqtt_16/store/ozwcache_0xfc20d090.xml at line 631 - No Help Entry for CommandClass 50, ValueID: 0 (-1):  (null) (Lang: )
2019-08-03 10:12:04.945 Warning, Localization::ReadXMLVIDHelp: Error in /opt/zwave2mqtt_16/store/ozwcache_0xfc20d090.xml at line 634 - No Help Entry for CommandClass 50, ValueID: 2 (-1):  (null) (Lang: )
2019-08-03 10:12:04.945 Warning, Localization::ReadXMLVIDHelp: Error in /opt/zwave2mqtt_16/store/ozwcache_0xfc20d090.xml at line 637 - No Help Entry for CommandClass 50, ValueID: 4 (-1):  (null) (Lang: )
2019-08-03 10:12:04.945 Warning, Localization::ReadXMLVIDHelp: Error in /opt/zwave2mqtt_16/store/ozwcache_0xfc20d090.xml at line 640 - No Help Entry for CommandClass 50, ValueID: 5 (-1):  (null) (Lang: )
2019-08-03 10:12:04.945 Warning, Localization::ReadXMLVIDHelp: Error in /opt/zwave2mqtt_16/store/ozwcache_0xfc20d090.xml at line 643 - No Help Entry for CommandClass 50, ValueID: 256 (-1):  (null) (Lang: )
2019-08-03 10:12:04.946 Warning, Localization::ReadXMLVIDHelp: Error in /opt/zwave2mqtt_16/store/ozwcache_0xfc20d090.xml at line 646 - No Help Entry for CommandClass 50, ValueID: 257 (-1):  (null) (Lang: )
2019-08-03 10:12:04.946 Info, Node004, (90 - COMMAND_CLASS_DEVICE_RESET_LOCALLY) - Compatibility Flags:
2019-08-03 10:12:04.946 Info, Node004, (90 - COMMAND_CLASS_DEVICE_RESET_LOCALLY) - State Flags:
2019-08-03 10:12:04.946 Info, Node004,   InNif: true
2019-08-03 10:12:04.946 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.946 Info, Node004, (94 - COMMAND_CLASS_ZWAVEPLUS_INFO) - Compatibility Flags:
2019-08-03 10:12:04.946 Info, Node004, (94 - COMMAND_CLASS_ZWAVEPLUS_INFO) - State Flags:
2019-08-03 10:12:04.946 Info, Node004,   InNif: true
2019-08-03 10:12:04.946 Info, Node004,   StaticRequests: 6
2019-08-03 10:12:04.946 Info, Node004, (112 - COMMAND_CLASS_CONFIGURATION) - Compatibility Flags:
2019-08-03 10:12:04.946 Info, Node004, (112 - COMMAND_CLASS_CONFIGURATION) - State Flags:
2019-08-03 10:12:04.946 Info, Node004,   InNif: true
2019-08-03 10:12:04.946 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.947 Info, Node004, (113 - COMMAND_CLASS_NOTIFICATION) - Compatibility Flags:
2019-08-03 10:12:04.947 Info, Node004, (113 - COMMAND_CLASS_NOTIFICATION) - State Flags:
2019-08-03 10:12:04.947 Info, Node004,   CCVersion: 4
2019-08-03 10:12:04.947 Info, Node004,   InNif: true
2019-08-03 10:12:04.947 Info, Node004,   StaticRequests: 0
2019-08-03 10:12:04.947 Info, Node004, (114 - COMMAND_CLASS_MANUFACTURER_SPECIFIC) - Compatibility Flags:
2019-08-03 10:12:04.947 Info, Node004, (114 - COMMAND_CLASS_MANUFACTURER_SPECIFIC) - State Flags:
2019-08-03 10:12:04.947 Info, Node004,   CCVersion: 2
2019-08-03 10:12:04.947 Info, Node004,   InNif: true
2019-08-03 10:12:04.947 Info, Node004,   StaticRequests: 0
2019-08-03 10:12:04.947 Info, Node004, (115 - COMMAND_CLASS_POWERLEVEL) - Compatibility Flags:
2019-08-03 10:12:04.947 Info, Node004, (115 - COMMAND_CLASS_POWERLEVEL) - State Flags:
2019-08-03 10:12:04.947 Info, Node004,   InNif: true
2019-08-03 10:12:04.947 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.947 Info, Node004, (129 - COMMAND_CLASS_CLOCK) - Compatibility Flags:
2019-08-03 10:12:04.947 Info, Node004, (129 - COMMAND_CLASS_CLOCK) - State Flags:
2019-08-03 10:12:04.947 Info, Node004,   InNif: true
2019-08-03 10:12:04.947 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.947 Info, Node004, (130 - COMMAND_CLASS_HAIL) - Compatibility Flags:
2019-08-03 10:12:04.947 Info, Node004, (130 - COMMAND_CLASS_HAIL) - State Flags:
2019-08-03 10:12:04.947 Info, Node004,   AfterMark: true
2019-08-03 10:12:04.947 Info, Node004,   InNif: true
2019-08-03 10:12:04.947 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.947 Info, Node004, (133 - COMMAND_CLASS_ASSOCIATION) - Compatibility Flags:
2019-08-03 10:12:04.947 Info, Node004, (133 - COMMAND_CLASS_ASSOCIATION) - State Flags:
2019-08-03 10:12:04.947 Info, Node004,   InNif: true
2019-08-03 10:12:04.947 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.948 Info, Node004, (134 - COMMAND_CLASS_VERSION) - Compatibility Flags:
2019-08-03 10:12:04.948 Info, Node004, (134 - COMMAND_CLASS_VERSION) - State Flags:
2019-08-03 10:12:04.948 Info, Node004,   InNif: true
2019-08-03 10:12:04.948 Info, Node004,   StaticRequests: 4
2019-08-03 10:12:04.948 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2019-08-03 10:12:04.948 Info, contrlr,     Node 001 - Known
2019-08-03 10:12:04.948 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2019-08-03 10:12:04.948 Detail, Node001, QueryStage_CacheLoad
2019-08-03 10:12:04.948 Info, Node001, Loading Cache for node 1: Manufacturer=Z-Wave.Me, Product=ZME_UZB1 USB Stick
2019-08-03 10:12:04.948 Info, Node001, Node Identity Codes: 0115:0400:0001
2019-08-03 10:12:04.948 Detail, Node001, QueryStage_Associations
2019-08-03 10:12:04.948 Detail, Node001, QueryStage_Neighbors
2019-08-03 10:12:04.948 Detail, contrlr, Requesting routing info (neighbor list) for Node 1
2019-08-03 10:12:04.948 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2019-08-03 10:12:04.948 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2019-08-03 10:12:04.948 Info, contrlr,     Node 002 - Known
2019-08-03 10:12:04.948 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2019-08-03 10:12:04.948 Detail, Node002, QueryStage_CacheLoad
2019-08-03 10:12:04.948 Info, Node002, Loading Cache for node 2: Manufacturer=AEON Labs, Product=ZW100 MultiSensor 6
2019-08-03 10:12:04.948 Info, Node002, Node Identity Codes: 0086:0002:0064
2019-08-03 10:12:04.948 Info, Node002, NoOperation::Set - Routing=true
2019-08-03 10:12:04.948 Detail, Node002, Queuing (NoOp) NoOperation_Set (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xca
2019-08-03 10:12:04.948 Detail, Node002, Queuing (Query) Query Stage Complete (CacheLoad)
2019-08-03 10:12:04.948 Info, contrlr,     Node 004 - Known
2019-08-03 10:12:04.948 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2019-08-03 10:12:04.948 Detail, Node004, QueryStage_CacheLoad
2019-08-03 10:12:04.948 Info, Node004, Loading Cache for node 4: Manufacturer=AEON Labs, Product=ZW116 Nano Switch
2019-08-03 10:12:04.948 Info, Node004, Node Identity Codes: 0086:0003:0074
2019-08-03 10:12:04.949 Info, Node004, NoOperation::Set - Routing=true
2019-08-03 10:12:04.949 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xcd
2019-08-03 10:12:04.949 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2019-08-03 10:12:04.949 Detail, contrlr,   Expected reply was received
2019-08-03 10:12:04.949 Detail, contrlr,   Message transaction complete
2019-08-03 10:12:04.949 Detail,
2019-08-03 10:12:04.949 Detail, contrlr, Removing current message
2019-08-03 10:12:04.949 Detail, Node001, Notification: DriverReady
2019-08-03 10:12:04.949 Detail, Node001, Notification: NodeAdded
2019-08-03 10:12:04.949 Detail, Node001, Notification: NodeProtocolInfo
2019-08-03 10:12:04.949 Detail, Node001, Notification: EssentialNodeQueriesComplete
2019-08-03 10:12:04.949 Detail, Node001, Notification: ValueAdded
2019-08-03 10:12:04.949 Detail, Node001, Notification: ValueAdded
2019-08-03 10:12:04.949 Detail, Node001, Notification: ValueAdded
2019-08-03 10:12:04.949 Detail, Node001, Notification: ValueAdded
2019-08-03 10:12:04.949 Detail, Node001, Notification: ValueAdded
2019-08-03 10:12:04.949 Detail, Node001, Notification: ValueAdded
2019-08-03 10:12:04.949 Detail, Node001, Notification: NodeNaming
2019-08-03 10:12:04.949 Detail, Node002, Notification: NodeAdded
2019-08-03 10:12:04.949 Detail, Node002, Notification: NodeProtocolInfo
2019-08-03T10:12:04.950Z z2m:Zwave Scanning network with homeid: 0xfc20d090
2019-08-03T10:12:04.950Z z2m:Zwave Node added 1
2019-08-03T10:12:04.951Z z2m:Zwave node 1 AVAILABLE: Z-Wave.Me - ZME_UZB1 USB Stick (Static PC Controller)
2019-08-03T10:12:04.952Z z2m:Zwave ValueAdded: 1-32-1-0 Basic
2019-08-03T10:12:04.955Z z2m:Zwave ValueAdded: 1-114-1-0 Loaded Config Revision
2019-08-03T10:12:04.955Z z2m:Zwave ValueAdded: 1-114-1-1 Config File Revision
2019-08-03T10:12:04.956Z z2m:Zwave ValueAdded: 1-114-1-2 Latest Available Config File Revision
2019-08-03T10:12:04.956Z z2m:Zwave ValueAdded: 1-114-1-3 Device ID
2019-08-03T10:12:04.957Z z2m:Zwave ValueAdded: 1-114-1-4 Serial Number
2019-08-03T10:12:04.957Z z2m:Zwave Node added 2
2019-08-03 10:12:04.957 Detail, Node002, Notification: EssentialNodeQueriesComplete
2019-08-03 10:12:04.957 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.957 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.957 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.957 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.957 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.957 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.957 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.957 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.957 Detail, Node002, Notification: ValueAdded
2019-08-03T10:12:04.959Z z2m:Zwave node 2 AVAILABLE: AEON Labs - ZW100 MultiSensor 6 (Home Security Sensor)
2019-08-03T10:12:04.959Z z2m:Zwave ValueAdded: 2-48-1-0 Sensor
2019-08-03T10:12:04.960Z z2m:Zwave ValueAdded: 2-49-1-1 Air Temperature
2019-08-03T10:12:04.960Z z2m:Zwave ValueAdded: 2-49-1-3 Illuminance
2019-08-03T10:12:04.961Z z2m:Zwave ValueAdded: 2-49-1-5 Humidity
2019-08-03T10:12:04.961Z z2m:Zwave ValueAdded: 2-49-1-27 Ultraviolet
2019-08-03T10:12:04.962Z z2m:Zwave ValueAdded: 2-49-1-256 Air Temperature Units
2019-08-03T10:12:04.962Z z2m:Zwave ValueAdded: 2-49-1-258 Illuminance Units
2019-08-03T10:12:04.963Z z2m:Zwave ValueAdded: 2-49-1-260 Humidity Units
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.963 Detail, Node002, Notification: ValueAdded
2019-08-03T10:12:04.963Z z2m:Zwave ValueAdded: 2-49-1-282 Ultraviolet Units
2019-08-03T10:12:04.964Z z2m:Zwave ValueAdded: 2-94-1-0 ZWave+ Version
2019-08-03T10:12:04.964Z z2m:Zwave ValueAdded: 2-94-1-1 InstallerIcon
2019-08-03T10:12:04.965Z z2m:Zwave ValueAdded: 2-94-1-2 UserIcon
2019-08-03T10:12:04.965Z z2m:Zwave ValueAdded: 2-112-1-2 Wake up 10 minutes on Power On
2019-08-03T10:12:04.966Z z2m:Zwave ValueAdded: 2-112-1-3 On time
2019-08-03T10:12:04.966Z z2m:Zwave ValueAdded: 2-112-1-4 Enable Motion Sensor
2019-08-03T10:12:04.967Z z2m:Zwave ValueAdded: 2-112-1-5 Command Options
2019-08-03T10:12:04.967Z z2m:Zwave ValueAdded: 2-112-1-8 Awake timeout
2019-08-03T10:12:04.968Z z2m:Zwave ValueAdded: 2-112-1-9 Current power mode
2019-08-03T10:12:04.968Z z2m:Zwave ValueAdded: 2-112-1-39 Low Battery
2019-08-03T10:12:04.969Z z2m:Zwave ValueAdded: 2-112-1-40 Report Only On Thresholds
2019-08-03T10:12:04.969Z z2m:Zwave ValueAdded: 2-112-1-41 Temperature Reporting Threshold
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.970 Detail, Node002, Notification: ValueAdded
2019-08-03T10:12:04.970Z z2m:Zwave ValueAdded: 2-112-1-42 Humidity Reporting Threshold
2019-08-03T10:12:04.970Z z2m:Zwave ValueAdded: 2-112-1-43 Luminance Reporting Threshold
2019-08-03T10:12:04.971Z z2m:Zwave ValueAdded: 2-112-1-44 Battery Reporting Threshold
2019-08-03T10:12:04.971Z z2m:Zwave ValueAdded: 2-112-1-45 UV Reporting Threshold
2019-08-03T10:12:04.972Z z2m:Zwave ValueAdded: 2-112-1-46 Low Temp Alarm
2019-08-03 10:12:04.972 Warning, Node002, GetBit Index 0 is not valid with BitMask 255
2019-08-03T10:12:04.972Z z2m:Zwave ValueAdded: 2-112-1-48 Enable/disable to send a report on Threshold
2019-08-03T10:12:04.973Z z2m:Zwave ValueAdded: 2-112-1-49 Set the upper limit value of temperature sensor
2019-08-03T10:12:04.973Z z2m:Zwave ValueAdded: 2-112-1-50 Set the lower limit value of temperature sensor
2019-08-03T10:12:04.974Z z2m:Zwave ValueAdded: 2-112-1-51 Set the upper limit value of humidity sensor
2019-08-03T10:12:04.974Z z2m:Zwave ValueAdded: 2-112-1-52 Set the lower limit value of humidity sensor
2019-08-03T10:12:04.975Z z2m:Zwave ValueAdded: 2-112-1-53 Set the upper limit value of Lighting sensor
2019-08-03T10:12:04.975Z z2m:Zwave ValueAdded: 2-112-1-54 Set the lower limit value of Lighting sensor
2019-08-03T10:12:04.976Z z2m:Zwave ValueAdded: 2-112-1-55 Set the upper limit value of ultraviolet sensor
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.976 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.977 Detail, Node002, Notification: ValueAdded
2019-08-03T10:12:04.977Z z2m:Zwave ValueAdded: 2-112-1-56 Set the lower limit value of ultraviolet sensor
2019-08-03T10:12:04.977Z z2m:Zwave ValueAdded: 2-112-1-57 Set the recover limit value of temperature sensor
2019-08-03T10:12:04.978Z z2m:Zwave ValueAdded: 2-112-1-58 Set the recover limit value of humidity sensor
2019-08-03T10:12:04.978Z z2m:Zwave ValueAdded: 2-112-1-59 Set the recover limit value of Lighting sensor
2019-08-03T10:12:04.979Z z2m:Zwave ValueAdded: 2-112-1-60 Set the recover limit value of Ultraviolet sensor
2019-08-03T10:12:04.979Z z2m:Zwave ValueAdded: 2-112-1-61 Get the out-of-limit state of the Sensors
2019-08-03T10:12:04.979Z z2m:Zwave ValueAdded: 2-112-1-64 Temperature scale
2019-08-03T10:12:04.980Z z2m:Zwave ValueAdded: 2-112-1-81 LED blinking report
2019-08-03T10:12:04.980Z z2m:Zwave ValueAdded: 2-112-1-100 Reset Params 101-103 to Default
2019-08-03 10:12:04.981 Warning, Node002, GetBit Index 0 is not valid with BitMask 241
2019-08-03 10:12:04.981 Warning, Node002, GetBit Index 2 is not valid with BitMask 241
2019-08-03 10:12:04.981 Warning, Node002, GetBit Index 3 is not valid with BitMask 241
2019-08-03 10:12:04.981 Warning, Node002, GetBit Index 4 is not valid with BitMask 241
2019-08-03T10:12:04.981Z z2m:Zwave ValueAdded: 2-112-1-101 Group 1 Reports
2019-08-03T10:12:04.981Z z2m:Zwave ValueAdded: 2-112-1-102 Group 2 Reports
2019-08-03T10:12:04.982Z z2m:Zwave ValueAdded: 2-112-1-103 Group 3 Reports
2019-08-03T10:12:04.982Z z2m:Zwave ValueAdded: 2-112-1-110 Reset Params 111-113 to Default
2019-08-03T10:12:04.983Z z2m:Zwave ValueAdded: 2-112-1-111 Group 1 Interval
2019-08-03T10:12:04.983Z z2m:Zwave ValueAdded: 2-112-1-112 Group 2 Interval
2019-08-03T10:12:04.984Z z2m:Zwave ValueAdded: 2-112-1-113 Group 3 Interval
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.984 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.985 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.985 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.985 Detail, Node002, Notification: ValueAdded
2019-08-03T10:12:04.985Z z2m:Zwave ValueAdded: 2-112-1-201 Temperature Calibration
2019-08-03T10:12:04.985Z z2m:Zwave ValueAdded: 2-112-1-202 Humidity Calibration
2019-08-03T10:12:04.986Z z2m:Zwave ValueAdded: 2-112-1-203 Luminance Calibration
2019-08-03T10:12:04.986Z z2m:Zwave ValueAdded: 2-112-1-204 Ultraviolet Calibration
2019-08-03T10:12:04.987Z z2m:Zwave ValueAdded: 2-112-1-252 Enable/disable Lock Configuration
2019-08-03T10:12:04.987Z z2m:Zwave ValueAdded: 2-112-1-255 Reset To Factory Defaults
2019-08-03T10:12:04.988Z z2m:Zwave ValueAdded: 2-113-1-7 Home Security
2019-08-03T10:12:04.988Z z2m:Zwave ValueAdded: 2-113-1-256 Previous Event Cleared
2019-08-03T10:12:04.988Z z2m:Zwave ValueAdded: 2-113-1-514 Automatically Clear Events
2019-08-03T10:12:04.989Z z2m:Zwave ValueAdded: 2-114-1-0 Loaded Config Revision
2019-08-03T10:12:04.989Z z2m:Zwave ValueAdded: 2-114-1-1 Config File Revision
2019-08-03T10:12:04.989Z z2m:Zwave ValueAdded: 2-114-1-2 Latest Available Config File Revision
2019-08-03T10:12:04.990Z z2m:Zwave ValueAdded: 2-114-1-3 Device ID
2019-08-03T10:12:04.990Z z2m:Zwave ValueAdded: 2-114-1-4 Serial Number
2019-08-03T10:12:04.991Z z2m:Zwave ValueAdded: 2-115-1-0 Powerlevel
2019-08-03T10:12:04.991Z z2m:Zwave ValueAdded: 2-115-1-1 Timeout
2019-08-03T10:12:04.991Z z2m:Zwave ValueAdded: 2-115-1-2 Set Powerlevel
2019-08-03T10:12:04.992Z z2m:Zwave ValueAdded: 2-115-1-3 Test Node
2019-08-03 10:12:04.992 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.992 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.992 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.992 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.992 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.992 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.992 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.992 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node002, Notification: Group
2019-08-03 10:12:04.993 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node002, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node002, Notification: NodeNaming
2019-08-03 10:12:04.993 Detail, Node004, Notification: NodeAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: NodeProtocolInfo
2019-08-03 10:12:04.993 Detail, Node004, Notification: EssentialNodeQueriesComplete
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:04.993 Detail, Node004, Notification: ValueAdded
2019-08-03T10:12:04.993Z z2m:Zwave ValueAdded: 2-115-1-4 Test Powerlevel
2019-08-03T10:12:04.994Z z2m:Zwave ValueAdded: 2-115-1-5 Frame Count
2019-08-03T10:12:04.994Z z2m:Zwave ValueAdded: 2-115-1-6 Test
2019-08-03T10:12:04.995Z z2m:Zwave ValueAdded: 2-115-1-7 Report
2019-08-03T10:12:04.995Z z2m:Zwave ValueAdded: 2-115-1-8 Test Status
2019-08-03T10:12:04.996Z z2m:Zwave ValueAdded: 2-115-1-9 Acked Frames
2019-08-03T10:12:04.996Z z2m:Zwave ValueAdded: 2-128-1-0 Battery Level
2019-08-03T10:12:04.996Z z2m:Zwave ValueAdded: 2-132-1-1 Minimum Wake-up Interval
2019-08-03T10:12:04.997Z z2m:Zwave ValueAdded: 2-132-1-2 Maximum Wake-up Interval
2019-08-03T10:12:04.997Z z2m:Zwave ValueAdded: 2-132-1-3 Default Wake-up Interval
2019-08-03T10:12:04.997Z z2m:Zwave ValueAdded: 2-132-1-4 Wake-up Interval Step
2019-08-03T10:12:04.998Z z2m:Zwave ValueAdded: 2-132-1-0 Wake-up Interval
2019-08-03T10:12:04.998Z z2m:Zwave ValueAdded: 2-134-1-0 Library Version
2019-08-03T10:12:04.999Z z2m:Zwave ValueAdded: 2-134-1-1 Protocol Version
2019-08-03T10:12:04.999Z z2m:Zwave ValueAdded: 2-134-1-2 Application Version
2019-08-03T10:12:04.999Z z2m:Zwave Node added 4
2019-08-03T10:12:04.999Z z2m:Zwave node 4 AVAILABLE: AEON Labs - ZW116 Nano Switch (On/Off Power Switch)
2019-08-03T10:12:05.000Z z2m:Zwave ValueAdded: 4-37-1-0 Switch
2019-08-03T10:12:05.000Z z2m:Zwave ValueAdded: 4-39-1-0 Switch All
2019-08-03T10:12:05.001Z z2m:Zwave ValueAdded: 4-43-1-0 Scene
2019-08-03T10:12:05.001Z z2m:Zwave ValueAdded: 4-43-1-1 Duration
2019-08-03T10:12:05.001Z z2m:Zwave ValueAdded: 4-50-1-0 Electric - kWh
2019-08-03T10:12:05.002Z z2m:Zwave ValueAdded: 4-50-1-2 Electric - W
2019-08-03T10:12:05.002Z z2m:Zwave ValueAdded: 4-50-1-4 Electric - V
2019-08-03T10:12:05.002Z z2m:Zwave ValueAdded: 4-50-1-5 Electric - A
2019-08-03T10:12:05.003Z z2m:Zwave ValueAdded: 4-50-1-256 Exporting
2019-08-03T10:12:05.003Z z2m:Zwave ValueAdded: 4-50-1-257 Reset
2019-08-03T10:12:05.004Z z2m:Zwave ValueAdded: 4-94-1-0 ZWave+ Version
2019-08-03T10:12:05.004Z z2m:Zwave ValueAdded: 4-94-1-1 InstallerIcon
2019-08-03T10:12:05.004Z z2m:Zwave ValueAdded: 4-94-1-2 UserIcon
2019-08-03T10:12:05.005Z z2m:Zwave ValueAdded: 4-112-1-3 Over current protection
2019-08-03T10:12:05.005Z z2m:Zwave ValueAdded: 4-112-1-4 Over heat protection
2019-08-03T10:12:05.006Z z2m:Zwave ValueAdded: 4-112-1-20 Status re-power on
2019-08-03T10:12:05.006Z z2m:Zwave ValueAdded: 4-112-1-21 WallSwipe IR sensor
2019-08-03T10:12:05.007Z z2m:Zwave ValueAdded: 4-112-1-64 WallSwipe button color
2019-08-03T10:12:05.007Z z2m:Zwave ValueAdded: 4-112-1-65 WallSwipe LED Up gesture action
2019-08-03T10:12:05.008Z z2m:Zwave ValueAdded: 4-112-1-66 WallSwipe LED Down gesture action
2019-08-03T10:12:05.008Z z2m:Zwave ValueAdded: 4-112-1-67 WallSwipe LED Left gesture action
2019-08-03 10:12:05.008 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.008 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.008 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.008 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.008 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.009 Detail, Node004, Notification: ValueAdded
2019-08-03T10:12:05.009Z z2m:Zwave ValueAdded: 4-112-1-68 WallSwipe LED Right gesture action
2019-08-03T10:12:05.010Z z2m:Zwave ValueAdded: 4-112-1-69 WallSwipe Night light
2019-08-03T10:12:05.010Z z2m:Zwave ValueAdded: 4-112-1-71 WallSwipe sensitivity
2019-08-03T10:12:05.011Z z2m:Zwave ValueAdded: 4-112-1-80 Notification report association group 1
2019-08-03T10:12:05.011Z z2m:Zwave ValueAdded: 4-112-1-81 Notification report using the external switch 1
2019-08-03T10:12:05.011Z z2m:Zwave ValueAdded: 4-112-1-82 Notification report using the external switch 2
2019-08-03T10:12:05.012Z z2m:Zwave ValueAdded: 4-112-1-83 LED status
2019-08-03T10:12:05.012Z z2m:Zwave ValueAdded: 4-112-1-84 Time set for Night light mode
2019-08-03T10:12:05.013Z z2m:Zwave ValueAdded: 4-112-1-86 Set the ON time of output load
2019-08-03T10:12:05.013Z z2m:Zwave ValueAdded: 4-112-1-87 Set the OFF time of output load
2019-08-03T10:12:05.014Z z2m:Zwave ValueAdded: 4-112-1-90 Enable/disable the parameter 91 and 92
2019-08-03T10:12:05.014Z z2m:Zwave ValueAdded: 4-112-1-91 Minimum Change to send Report (Watt)
2019-08-03T10:12:05.015Z z2m:Zwave ValueAdded: 4-112-1-92 Minimum Change to send Report (%)
2019-08-03T10:12:05.015Z z2m:Zwave ValueAdded: 4-112-1-100 Default Group Reports
2019-08-03T10:12:05.016Z z2m:Zwave ValueAdded: 4-112-1-101 Report type sent in Reporting Group 1
2019-08-03T10:12:05.016Z z2m:Zwave ValueAdded: 4-112-1-102 Report type sent in Reporting Group 2
2019-08-03T10:12:05.017Z z2m:Zwave ValueAdded: 4-112-1-103 Report type sent in Reporting Group 3
2019-08-03T10:12:05.017Z z2m:Zwave ValueAdded: 4-112-1-110 Set 111-113 to default
2019-08-03T10:12:05.017Z z2m:Zwave ValueAdded: 4-112-1-111 Send Interval for Reporting Group 1
2019-08-03T10:12:05.018Z z2m:Zwave ValueAdded: 4-112-1-112 Send Interval for Reporting Group 2
2019-08-03T10:12:05.018Z z2m:Zwave ValueAdded: 4-112-1-113 Send Interval for Reporting Group 3
2019-08-03T10:12:05.019Z z2m:Zwave ValueAdded: 4-112-1-120 Set the external S1 mode
2019-08-03T10:12:05.019Z z2m:Zwave ValueAdded: 4-112-1-121 Set the external S2 mode
2019-08-03T10:12:05.020Z z2m:Zwave ValueAdded: 4-112-1-122 Control destination for S1
2019-08-03T10:12:05.020Z z2m:Zwave ValueAdded: 4-112-1-123 Control destination for S2
2019-08-03T10:12:05.021Z z2m:Zwave ValueAdded: 4-112-1-144 WallSwipe
2019-08-03T10:12:05.021Z z2m:Zwave ValueAdded: 4-112-1-247 S1/S2 when the Switch mode is 3-way switch
2019-08-03T10:12:05.022Z z2m:Zwave ValueAdded: 4-112-1-248 Function of S1/S2
2019-08-03T10:12:05.022Z z2m:Zwave ValueAdded: 4-112-1-251 Configuration factory reset function
2019-08-03T10:12:05.023Z z2m:Zwave ValueAdded: 4-112-1-252 Configuration Locked
2019-08-03T10:12:05.023Z z2m:Zwave ValueAdded: 4-112-1-255 Reset To Factory Defaults
2019-08-03 10:12:05.023 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.023 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.023 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: Group
2019-08-03 10:12:05.024 Detail, Node004, Notification: Group
2019-08-03 10:12:05.024 Detail, Node004, Notification: Group
2019-08-03 10:12:05.024 Detail, Node004, Notification: Group
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: ValueAdded
2019-08-03 10:12:05.024 Detail, Node004, Notification: NodeNaming
2019-08-03 10:12:05.024 Detail,
2019-08-03 10:12:05.024 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-08-03 10:12:05.024 Info, contrlr, Encrypted Flag is 0
2019-08-03T10:12:05.024Z z2m:Zwave ValueAdded: 4-113-1-4 Heat
2019-08-03T10:12:05.025Z z2m:Zwave ValueAdded: 4-113-1-8 Power Management
2019-08-03T10:12:05.025Z z2m:Zwave ValueAdded: 4-113-1-256 Previous Event Cleared
2019-08-03T10:12:05.025Z z2m:Zwave ValueAdded: 4-114-1-0 Loaded Config Revision
2019-08-03T10:12:05.026Z z2m:Zwave ValueAdded: 4-114-1-1 Config File Revision
2019-08-03 10:12:05.026 Detail, contrlr,   Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
2019-08-03 10:12:05.026 Detail,
2019-08-03 10:12:05.026 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2019-08-03 10:12:05.026 Detail, contrlr,   Expected reply was received
2019-08-03 10:12:05.026 Detail, contrlr,   Message transaction complete
2019-08-03 10:12:05.026 Detail,
2019-08-03T10:12:05.026Z z2m:Zwave ValueAdded: 4-114-1-2 Latest Available Config File Revision
2019-08-03 10:12:05.026 Detail, contrlr, Removing current message
2019-08-03 10:12:05.026 Detail,
2019-08-03 10:12:05.026 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x08, 0x00, 0x03, 0x01, 0x02, 0x01, 0x01, 0x5e, 0xa9
2019-08-03 10:12:05.026 Info, contrlr, Encrypted Flag is 0
2019-08-03T10:12:05.026Z z2m:Zwave ValueAdded: 4-114-1-3 Device ID
2019-08-03T10:12:05.027Z z2m:Zwave ValueAdded: 4-114-1-4 Serial Number
2019-08-03T10:12:05.027Z z2m:Zwave ValueAdded: 4-115-1-0 Powerlevel
2019-08-03T10:12:05.028Z z2m:Zwave ValueAdded: 4-115-1-1 Timeout
2019-08-03 10:12:05.028 Detail, contrlr, Removing current message
2019-08-03 10:12:05.028 Detail,
2019-08-03 10:12:05.028 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-08-03 10:12:05.028 Info, Node001, Encrypted Flag is 0
2019-08-03T10:12:05.028Z z2m:Zwave ValueAdded: 4-115-1-2 Set Powerlevel
2019-08-03T10:12:05.029Z z2m:Zwave ValueAdded: 4-115-1-3 Test Node
2019-08-03T10:12:05.029Z z2m:Zwave ValueAdded: 4-115-1-4 Test Powerlevel
2019-08-03T10:12:05.029Z z2m:Zwave ValueAdded: 4-115-1-5 Frame Count
2019-08-03T10:12:05.030Z z2m:Zwave ValueAdded: 4-115-1-6 Test
2019-08-03T10:12:05.030Z z2m:Zwave ValueAdded: 4-115-1-7 Report
2019-08-03T10:12:05.031Z z2m:Zwave ValueAdded: 4-115-1-8 Test Status
2019-08-03T10:12:05.031Z z2m:Zwave ValueAdded: 4-115-1-9 Acked Frames
2019-08-03T10:12:05.031Z z2m:Zwave ValueAdded: 4-129-1-0 Day
2019-08-03T10:12:05.032Z z2m:Zwave ValueAdded: 4-129-1-1 Hour
2019-08-03T10:12:05.032Z z2m:Zwave ValueAdded: 4-129-1-2 Minute
2019-08-03T10:12:05.033Z z2m:Zwave ValueAdded: 4-134-1-0 Library Version
2019-08-03T10:12:05.036Z z2m:Zwave ValueAdded: 4-134-1-1 Protocol Version
2019-08-03T10:12:05.037Z z2m:Zwave ValueAdded: 4-134-1-2 Application Version
2019-08-03 10:12:05.037 Detail, Node001,   Received: 0x01, 0x20, 0x01, 0x80, 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, 0x00, 0x5e
2019-08-03 10:12:05.037 Detail,
2019-08-03 10:12:05.037 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2019-08-03 10:12:05.037 Info, Node001,     Neighbors of this node are:
2019-08-03 10:12:05.037 Info, Node001,  (none reported)
2019-08-03 10:12:05.037 Detail, Node001,   Expected reply was received
2019-08-03 10:12:05.037 Detail, Node001,   Message transaction complete
2019-08-03 10:12:05.037 Detail,
2019-08-03 10:12:05.037 Detail, Node001, Removing current message
2019-08-03 10:12:05.037 Detail,
2019-08-03 10:12:05.037 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-08-03 10:12:05.037 Info, Node002, Encrypted Flag is 0
2019-08-03 10:12:05.045 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:05.045 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:05.187 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x0a, 0x00, 0x00, 0x0e, 0x01, 0xa5, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x04, 0x00, 0x00, 0x00, 0x01, 0x01, 0x00, 0x00, 0x53
2019-08-03 10:12:05.187 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a)
2019-08-03 10:12:05.187 Info, Node002, Request RTT 150 Average Request RTT 150
2019-08-03 10:12:05.188 Detail, Node002, Extended TxStatus: Time: 140, Hops: 1, Rssi:  -91 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 4 0 0 0, RouteSpeed: 9600, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:05.188 Detail, Node002,   Expected callbackId was received
2019-08-03 10:12:05.188 Detail, Node002,   Expected reply was received
2019-08-03 10:12:05.188 Detail, Node002,   Message transaction complete
2019-08-03 10:12:05.188 Detail,
2019-08-03 10:12:05.188 Detail, Node002, Removing current message
2019-08-03 10:12:05.188 Detail, Node002, Notification: Notification - NoOperation
2019-08-03 10:12:05.188 Detail,
2019-08-03 10:12:05.188 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-08-03 10:12:05.188 Info, Node004, Encrypted Flag is 0
2019-08-03T10:12:05.188Z z2m:Zwave Notification from node 2: Notification - NoOperation (2)
2019-08-03 10:12:05.195 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:05.195 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:09.415 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x0b, 0x01, 0x01, 0xa6, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x24
2019-08-03 10:12:09.415 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b)
2019-08-03 10:12:09.416 Detail, Node004, Extended TxStatus: Time: 4220, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:09.416 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:12:09.416 Warning, Node004, WARNING: Device is not a sleeping node.
2019-08-03 10:12:09.416 Info, Node004, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 0 pending 1
2019-08-03 10:12:09.416 Detail, Node004,   Expected callbackId was received
2019-08-03 10:12:09.416 Detail, Node004,   Expected reply was received
2019-08-03 10:12:09.416 Detail, Node004,   Message transaction complete
2019-08-03 10:12:09.416 Detail,
2019-08-03 10:12:09.416 Detail, Node004, Removing current message
2019-08-03 10:12:09.416 Detail, Node004, Notification: Notification - NoOperation
2019-08-03 10:12:09.416 Detail, Node001, Query Stage Complete (Neighbors)
2019-08-03 10:12:09.416 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2019-08-03 10:12:09.416 Detail, Node001, QueryStage_Session
2019-08-03 10:12:09.416 Detail, Node001, QueryStage_Dynamic
2019-08-03 10:12:09.416 Detail, Node001, QueryStage_Configuration
2019-08-03 10:12:09.416 Detail, Node001, QueryStage_Complete
2019-08-03 10:12:09.416 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2019-08-03 10:12:09.416 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0
2019-08-03 10:12:09.416 Info, Saving Cache
2019-08-03T10:12:09.416Z z2m:Zwave Notification from node 4: Notification - NoOperation (2)
2019-08-03 10:12:09.416 Info, Node001, Cache Save for Node 1 as its QueryStage_CacheLoad
2019-08-03 10:12:09.417 Info, Node002, Cache Save for Node 2 as its QueryStage_CacheLoad
2019-08-03 10:12:09.417 Info, Node004, Cache Save for Node 4 as its QueryStage_CacheLoad
2019-08-03 10:12:09.421 Detail, Node001, Notification: NodeQueriesComplete
2019-08-03 10:12:09.421 Detail, Node002, Query Stage Complete (CacheLoad)
2019-08-03 10:12:09.421 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
2019-08-03 10:12:09.421 Detail, Node002, QueryStage_Associations
2019-08-03 10:12:09.421 Info, Node002, Number of association groups reported for node 2 is 1.
2019-08-03 10:12:09.421 Info, Node002, Get Associations for group 1 of node 2
2019-08-03 10:12:09.421 Detail, Node002, Queuing (Send) AssociationCmd_Get (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x85, 0x02, 0x01, 0x25, 0x0c, 0x48
2019-08-03 10:12:09.421 Detail, Node002, Queuing (Query) Query Stage Complete (Associations)
2019-08-03 10:12:09.421 Detail,
2019-08-03 10:12:09.421 Info, Node002, Sending (Send) message (Callback ID=0x0c, Expected Reply=0x04) - AssociationCmd_Get (Node=2): 0x01, 0x0a, 0x00, 0x13, 0x02, 0x03, 0x85, 0x02, 0x01, 0x25, 0x0c, 0x48
2019-08-03 10:12:09.421 Info, Node002, Encrypted Flag is 0
2019-08-03T10:12:09.426Z z2m:Zwave node 1 ready: Z-Wave.Me - ZME_UZB1 USB Stick (Static PC Controller)
2019-08-03 10:12:09.429 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:09.429 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:09.574 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x0c, 0x00, 0x00, 0x0f, 0x01, 0xa7, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x04, 0x00, 0x00, 0x00, 0x01, 0x01, 0x00, 0x00, 0x56
2019-08-03 10:12:09.574 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0c received (expected 0x0c)
2019-08-03 10:12:09.574 Info, Node002, Request RTT 152 Average Request RTT 151
2019-08-03 10:12:09.574 Detail, Node002, Extended TxStatus: Time: 150, Hops: 1, Rssi:  -89 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 4 0 0 0, RouteSpeed: 9600, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:09.574 Detail, Node002,   Expected callbackId was received
2019-08-03 10:12:09.698 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x85, 0x03, 0x01, 0x05, 0x00, 0x01, 0xa7, 0x00, 0xd5
2019-08-03 10:12:09.698 Detail,
2019-08-03 10:12:09.698 Info, Node002, Response RTT 276 Average Response RTT 276
2019-08-03 10:12:09.698 Info, Node002, Received Association report from node 2, group 1, containing 1 associations
2019-08-03 10:12:09.698 Info, Node002,   The group contains:
2019-08-03 10:12:09.698 Info, Node002,     Node 1
2019-08-03 10:12:09.698 Info, Node002, Querying associations for node 2 is complete.
2019-08-03 10:12:09.698 Detail, Node002,   Expected reply and command class was received
2019-08-03 10:12:09.698 Detail, Node002,   Message transaction complete
2019-08-03 10:12:09.698 Detail,
2019-08-03 10:12:09.698 Detail, Node002, Removing current message
2019-08-03 10:12:09.699 Detail, Node004, Query Stage Complete (CacheLoad)
2019-08-03 10:12:09.699 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=1 queryStage=CacheLoad live=1
2019-08-03 10:12:09.699 Detail, Node004, QueryStage_CacheLoad
2019-08-03 10:12:09.699 Info, Node004, Loading Cache for node 4: Manufacturer=AEON Labs, Product=ZW116 Nano Switch
2019-08-03 10:12:09.699 Info, Node004, Node Identity Codes: 0086:0003:0074
2019-08-03 10:12:09.699 Info, Node004, NoOperation::Set - Routing=true
2019-08-03 10:12:09.699 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0d, 0xcb
2019-08-03 10:12:09.699 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2019-08-03 10:12:09.699 Detail,
2019-08-03 10:12:09.699 Info, Node004, Sending (NoOp) message (Callback ID=0x0d, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0d, 0xcb
2019-08-03 10:12:09.699 Info, Node004, Encrypted Flag is 0
2019-08-03 10:12:09.708 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:09.708 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:09.945 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x85, 0x03, 0x01, 0x05, 0x00, 0x01, 0xa8, 0x00, 0xda
2019-08-03 10:12:09.945 Detail,
2019-08-03 10:12:09.945 Info, Node002, Received Association report from node 2, group 1, containing 1 associations
2019-08-03 10:12:09.945 Info, Node002,   The group contains:
2019-08-03 10:12:09.945 Info, Node002,     Node 1
2019-08-03 10:12:10.047 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x85, 0x03, 0x01, 0x05, 0x00, 0x01, 0xa4, 0x00, 0xd6
2019-08-03 10:12:10.047 Detail,
2019-08-03 10:12:10.047 Info, Node002, Received Association report from node 2, group 1, containing 1 associations
2019-08-03 10:12:10.047 Info, Node002,   The group contains:
2019-08-03 10:12:10.047 Info, Node002,     Node 1
2019-08-03 10:12:14.464 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x0d, 0x01, 0x01, 0xdc, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x58
2019-08-03 10:12:14.464 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x0d)
2019-08-03 10:12:14.464 Detail, Node004, Extended TxStatus: Time: 4760, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:14.464 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:12:14.464 Warning, Node004, WARNING: Device is not a sleeping node.
2019-08-03 10:12:14.464 Info, Node004, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 1 pending 1
2019-08-03 10:12:14.464 Detail, Node004,   Expected callbackId was received
2019-08-03 10:12:14.464 Detail, Node004,   Expected reply was received
2019-08-03 10:12:14.464 Detail, Node004,   Message transaction complete
2019-08-03 10:12:14.464 Detail,
2019-08-03 10:12:14.464 Detail, Node004, Removing current message
2019-08-03 10:12:14.464 Detail, Node004, Notification: Notification - NoOperation
2019-08-03 10:12:14.464 Detail, Node002, Query Stage Complete (Associations)
2019-08-03 10:12:14.464 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Neighbors live=1
2019-08-03 10:12:14.464 Detail, Node002, QueryStage_Neighbors
2019-08-03 10:12:14.465 Detail, Requesting routing info (neighbor list) for Node 2
2019-08-03 10:12:14.465 Detail, Node002, Queuing (Command) Get Routing Info (Node=2): 0x01, 0x07, 0x00, 0x80, 0x02, 0x00, 0x00, 0x03, 0x79
2019-08-03 10:12:14.465 Detail, Node002, Queuing (Query) Query Stage Complete (Neighbors)
2019-08-03 10:12:14.465 Detail,
2019-08-03 10:12:14.465 Info, Node002, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=2): 0x01, 0x07, 0x00, 0x80, 0x02, 0x00, 0x00, 0x03, 0x79
2019-08-03 10:12:14.465 Info, Node002, Encrypted Flag is 0
2019-08-03T10:12:14.466Z z2m:Zwave Notification from node 4: Notification - NoOperation (2)
2019-08-03 10:12:14.469 Detail, Node002,   Received: 0x01, 0x20, 0x01, 0x80, 0x08, 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, 0x56
2019-08-03 10:12:14.470 Detail,
2019-08-03 10:12:14.470 Info, Node002, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2019-08-03 10:12:14.470 Info, Node002,     Neighbors of this node are:
2019-08-03 10:12:14.470 Info, Node002,     Node 4
2019-08-03 10:12:14.470 Detail, Node002,   Expected reply was received
2019-08-03 10:12:14.470 Detail, Node002,   Message transaction complete
2019-08-03 10:12:14.470 Detail,
2019-08-03 10:12:14.470 Detail, Node002, Removing current message
2019-08-03 10:12:14.470 Detail, Node004, Query Stage Complete (CacheLoad)
2019-08-03 10:12:14.470 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=2 queryStage=CacheLoad live=1
2019-08-03 10:12:14.470 Detail, Node004, QueryStage_CacheLoad
2019-08-03 10:12:14.470 Info, Node004, Loading Cache for node 4: Manufacturer=AEON Labs, Product=ZW116 Nano Switch
2019-08-03 10:12:14.470 Info, Node004, Node Identity Codes: 0086:0003:0074
2019-08-03 10:12:14.470 Info, Node004, NoOperation::Set - Routing=true
2019-08-03 10:12:14.470 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0e, 0xc8
2019-08-03 10:12:14.470 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2019-08-03 10:12:14.470 Detail,
2019-08-03 10:12:14.470 Info, Node004, Sending (NoOp) message (Callback ID=0x0e, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x0e, 0xc8
2019-08-03 10:12:14.470 Info, Node004, Encrypted Flag is 0
2019-08-03 10:12:14.486 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:14.486 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:18.619 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x0e, 0x01, 0x01, 0x9e, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x04, 0x00, 0x00, 0x1a
2019-08-03 10:12:18.620 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x0e received (expected 0x0e)
2019-08-03 10:12:18.620 Detail, Node004, Extended TxStatus: Time: 4140, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 4, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:18.620 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:12:18.620 Warning, Node004, WARNING: Device is not a sleeping node.
2019-08-03 10:12:18.620 Error, Node004, ERROR: node presumed dead
2019-08-03 10:12:18.620 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2019-08-03 10:12:18.620 Warning, CheckCompletedNodeQueries all=0, deadFound=1 sleepingOnly=0
2019-08-03 10:12:18.620 Info, Saving Cache
2019-08-03 10:12:18.620 Info, Node001, Cache Save for Node 1 as its QueryStage_CacheLoad
2019-08-03 10:12:18.621 Info, Node002, Cache Save for Node 2 as its QueryStage_CacheLoad
2019-08-03 10:12:18.622 Info, Node004, Cache Save for Node 4 as its QueryStage_CacheLoad
2019-08-03 10:12:18.629 Info, Node004, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 2 pending 1
2019-08-03 10:12:18.629 Detail, Node004,   Expected callbackId was received
2019-08-03 10:12:18.629 Detail, Node004,   Expected reply was received
2019-08-03 10:12:18.629 Detail, Node004,   Message transaction complete
2019-08-03 10:12:18.629 Detail,
2019-08-03 10:12:18.629 Detail, Node004, Removing current message
2019-08-03 10:12:18.629 Detail, Node004, Notification: Notification - NoOperation
2019-08-03 10:12:18.629 Detail, Node004, Notification: Notification - Node Dead
2019-08-03 10:12:18.629 Detail, Node002, Query Stage Complete (Neighbors)
2019-08-03 10:12:18.629 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2019-08-03 10:12:18.629 Detail, Node002, QueryStage_Session
2019-08-03 10:12:18.629 Detail, Node002, Queuing (Query) PowerlevelCmd_Get (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x73, 0x02, 0x25, 0x0f, 0xbe
2019-08-03T10:12:18.629Z z2m:Zwave Notification from node 4: Notification - NoOperation (2)
2019-08-03 10:12:18.629 Detail, Node002, Queuing (Query) WakeUpCmd_IntervalGet (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x05, 0x25, 0x10, 0x51
2019-08-03 10:12:18.629 Detail, Node002, Queuing (Query) Query Stage Complete (Session)
2019-08-03 10:12:18.629 Detail, Node004, Query Stage Complete (CacheLoad)
2019-08-03 10:12:18.629 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=0
2019-08-03 10:12:18.629 Detail,
2019-08-03 10:12:18.630 Info, Node002, Sending (Query) message (Callback ID=0x0f, Expected Reply=0x04) - PowerlevelCmd_Get (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x73, 0x02, 0x25, 0x0f, 0xbe
2019-08-03 10:12:18.630 Info, Node002, Encrypted Flag is 0
2019-08-03T10:12:18.633Z z2m:Zwave Notification from node 4: Notification - Node Dead (5)
2019-08-03 10:12:18.637 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:18.637 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:18.780 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x0f, 0x00, 0x00, 0x0e, 0x01, 0xa7, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x04, 0x00, 0x00, 0x00, 0x01, 0x01, 0x00, 0x00, 0x54
2019-08-03 10:12:18.780 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x0f received (expected 0x0f)
2019-08-03 10:12:18.780 Info, Node002, Request RTT 150 Average Request RTT 150
2019-08-03 10:12:18.780 Detail, Node002, Extended TxStatus: Time: 140, Hops: 1, Rssi:  -89 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 4 0 0 0, RouteSpeed: 9600, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:18.780 Detail, Node002,   Expected callbackId was received
2019-08-03 10:12:18.905 Detail, Node002,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x02, 0x04, 0x73, 0x03, 0x00, 0x00, 0xa7, 0x00, 0x26
2019-08-03 10:12:18.905 Detail,
2019-08-03 10:12:18.905 Info, Node002, Response RTT 275 Average Response RTT 275
2019-08-03 10:12:18.905 Info, Node002, Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2019-08-03 10:12:18.905 Detail, Node002, Initial read of value
2019-08-03 10:12:18.905 Detail, Node002, Initial read of value
2019-08-03 10:12:18.905 Detail, Node002,   Expected reply and command class was received
2019-08-03 10:12:18.905 Detail, Node002,   Message transaction complete
2019-08-03 10:12:18.905 Detail,
2019-08-03 10:12:18.905 Detail, Node002, Removing current message
2019-08-03 10:12:18.906 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:18.906 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:18.906 Detail,
2019-08-03 10:12:18.906 Info, Node002, Sending (Query) message (Callback ID=0x10, Expected Reply=0x04) - WakeUpCmd_IntervalGet (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x05, 0x25, 0x10, 0x51
2019-08-03 10:12:18.906 Info, Node002, Encrypted Flag is 0
2019-08-03 10:12:18.914 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:18.914 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:19.173 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x10, 0x00, 0x00, 0x1a, 0x01, 0xa5, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x04, 0x00, 0x00, 0x00, 0x01, 0x01, 0x00, 0x00, 0x5d
2019-08-03 10:12:19.173 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x10 received (expected 0x10)
2019-08-03 10:12:19.173 Info, Node002, Request RTT 267 Average Request RTT 208
2019-08-03 10:12:19.173 Detail, Node002, Extended TxStatus: Time: 260, Hops: 1, Rssi:  -91 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 4 0 0 0, RouteSpeed: 9600, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:19.173 Detail, Node002,   Expected callbackId was received
2019-08-03 10:12:19.634 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x84, 0x06, 0x00, 0x00, 0xf0, 0x01, 0xa7, 0x00, 0x25
2019-08-03 10:12:19.634 Detail,
2019-08-03 10:12:19.634 Info, Node002, Response RTT 729 Average Response RTT 502
2019-08-03 10:12:19.634 Info, Node002, Received Wakeup Interval report from node 2: Interval=240, Target Node=1
2019-08-03 10:12:19.634 Detail, Node002, Initial read of value
2019-08-03 10:12:19.634 Detail, Node002,   Expected reply and command class was received
2019-08-03 10:12:19.635 Detail, Node002,   Message transaction complete
2019-08-03 10:12:19.635 Detail,
2019-08-03 10:12:19.635 Detail, Node002, Removing current message
2019-08-03 10:12:19.635 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:19.635 Detail, Node002, Query Stage Complete (Session)
2019-08-03 10:12:19.635 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=1
2019-08-03 10:12:19.635 Detail, Node002, QueryStage_Dynamic
2019-08-03 10:12:19.635 Detail, Node002, Queuing (Send) SensorBinaryCmd_Get (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x30, 0x02, 0x25, 0x11, 0xe3
2019-08-03 10:12:19.635 Detail, Node002, Queuing (Send) SensorMultilevelCmd_Get (Node=2): 0x01, 0x0b, 0x00, 0x13, 0x02, 0x04, 0x31, 0x04, 0x01, 0x00, 0x25, 0x12, 0xe2
2019-08-03 10:12:19.635 Detail, Node002, Queuing (Send) SensorMultilevelCmd_Get (Node=2): 0x01, 0x0b, 0x00, 0x13, 0x02, 0x04, 0x31, 0x04, 0x03, 0x01, 0x25, 0x13, 0xe0
2019-08-03 10:12:19.635 Detail, Node002, Queuing (Send) SensorMultilevelCmd_Get (Node=2): 0x01, 0x0b, 0x00, 0x13, 0x02, 0x04, 0x31, 0x04, 0x05, 0x00, 0x25, 0x14, 0xe0
2019-08-03 10:12:19.635 Detail, Node002, Queuing (Send) SensorMultilevelCmd_Get (Node=2): 0x01, 0x0b, 0x00, 0x13, 0x02, 0x04, 0x31, 0x04, 0x1b, 0x00, 0x25, 0x15, 0xff
2019-08-03 10:12:19.635 Info, Node002, AlarmCmd_Get Not Supported on this node
2019-08-03 10:12:19.635 Detail, Node002, Queuing (Send) BatteryCmd_Get (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x80, 0x02, 0x25, 0x16, 0x54
2019-08-03 10:12:19.635 Detail, Node002, Queuing (Query) Query Stage Complete (Dynamic)
2019-08-03 10:12:19.635 Detail,
2019-08-03 10:12:19.635 Info, Node002, Sending (Send) message (Callback ID=0x11, Expected Reply=0x04) - SensorBinaryCmd_Get (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x30, 0x02, 0x25, 0x11, 0xe3
2019-08-03 10:12:19.636 Info, Node002, Encrypted Flag is 0
2019-08-03 10:12:19.644 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:19.644 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:19.903 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x11, 0x00, 0x00, 0x1a, 0x01, 0xa8, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x04, 0x00, 0x00, 0x00, 0x01, 0x01, 0x00, 0x00, 0x51
2019-08-03 10:12:19.903 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x11 received (expected 0x11)
2019-08-03 10:12:19.903 Info, Node002, Request RTT 267 Average Request RTT 237
2019-08-03 10:12:19.903 Detail, Node002, Extended TxStatus: Time: 260, Hops: 1, Rssi:  -88 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 4 0 0 0, RouteSpeed: 9600, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:19.903 Detail, Node002,   Expected callbackId was received
2019-08-03 10:12:20.022 Detail, Node002,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x02, 0x03, 0x30, 0x03, 0xff, 0xa8, 0x00, 0x95
2019-08-03 10:12:20.022 Detail,
2019-08-03 10:12:20.022 Info, Node002, Response RTT 387 Average Response RTT 444
2019-08-03 10:12:20.022 Info, Node002, Received SensorBinary report: Sensor:168 State=On
2019-08-03 10:12:20.023 Detail, Node002, Initial read of value
2019-08-03 10:12:20.023 Detail, Node002,   Expected reply and command class was received
2019-08-03 10:12:20.023 Detail, Node002,   Message transaction complete
2019-08-03 10:12:20.023 Detail,
2019-08-03 10:12:20.023 Detail, Node002, Removing current message
2019-08-03 10:12:20.023 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:20.023 Detail,
2019-08-03 10:12:20.023 Info, Node002, Sending (Send) message (Callback ID=0x12, Expected Reply=0x04) - SensorMultilevelCmd_Get (Node=2): 0x01, 0x0b, 0x00, 0x13, 0x02, 0x04, 0x31, 0x04, 0x01, 0x00, 0x25, 0x12, 0xe2
2019-08-03 10:12:20.023 Info, Node002, Encrypted Flag is 0
2019-08-03 10:12:20.032 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:20.032 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:20.295 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x12, 0x00, 0x00, 0x1b, 0x01, 0xa5, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x03, 0x04, 0x00, 0x00, 0x00, 0x01, 0x01, 0x00, 0x00, 0x5e
2019-08-03 10:12:20.295 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x12 received (expected 0x12)
2019-08-03 10:12:20.295 Info, Node002, Request RTT 271 Average Request RTT 254
2019-08-03 10:12:20.295 Detail, Node002, Extended TxStatus: Time: 270, Hops: 1, Rssi:  -91 --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Last Working Route, Route: 4 0 0 0, RouteSpeed: 9600, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:20.295 Detail, Node002,   Expected callbackId was received
2019-08-03 10:12:20.750 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x01, 0x22, 0x00, 0xeb, 0xa7, 0x00, 0xaa
2019-08-03 10:12:20.750 Detail,
2019-08-03 10:12:20.750 Info, Node002, Response RTT 727 Average Response RTT 585
2019-08-03 10:12:20.750 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Air Temperature: value=23.5C
2019-08-03 10:12:20.750 Detail, Node002, Initial read of value
2019-08-03 10:12:20.751 Detail, Node002,   Expected reply and command class was received
2019-08-03 10:12:20.751 Detail, Node002,   Message transaction complete
2019-08-03 10:12:20.751 Detail,
2019-08-03 10:12:20.751 Detail, Node002, Removing current message
2019-08-03 10:12:20.751 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:20.751 Detail,
2019-08-03 10:12:20.751 Info, Node002, Sending (Send) message (Callback ID=0x13, Expected Reply=0x04) - SensorMultilevelCmd_Get (Node=2): 0x01, 0x0b, 0x00, 0x13, 0x02, 0x04, 0x31, 0x04, 0x03, 0x01, 0x25, 0x13, 0xe0
2019-08-03 10:12:20.751 Info, Node002, Encrypted Flag is 0
2019-08-03 10:12:20.760 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:20.760 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:25.371 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x13, 0x01, 0x01, 0xcd, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x57
2019-08-03 10:12:25.371 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x13 received (expected 0x13)
2019-08-03 10:12:25.372 Detail, Node002, Extended TxStatus: Time: 4610, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:25.372 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:12:25.372 Warning, Node002, WARNING: Device is not a sleeping node.
2019-08-03 10:12:25.372 Detail, Node002,   Expected callbackId was received
2019-08-03 10:12:25.933 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x01, 0x41, 0xda, 0x00, 0x3c, 0x00, 0x01, 0x41, 0xd9, 0xa5, 0x00, 0x08
2019-08-03 10:12:25.933 Detail,
2019-08-03 10:12:25.933 Error, Node004, WARNING: node revived
2019-08-03 10:12:25.933 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2019-08-03 10:12:25.933 Detail, Node004, QueryStage_CacheLoad
2019-08-03 10:12:25.933 Info, Node004, Loading Cache for node 4: Manufacturer=AEON Labs, Product=ZW116 Nano Switch
2019-08-03 10:12:25.934 Info, Node004, Node Identity Codes: 0086:0003:0074
2019-08-03 10:12:25.934 Info, Node004, NoOperation::Set - Routing=true
2019-08-03 10:12:25.934 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x17, 0xd1
2019-08-03 10:12:25.934 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2019-08-03 10:12:25.934 Info, Node004, Received Meter Report for Electric - kWh (1) with Units kWh (0) on Index 0: 82.394
2019-08-03 10:12:25.934 Detail, Node004, Initial read of value
2019-08-03 10:12:25.934 Detail, Node004, Initial read of value
2019-08-03 10:12:25.934 Detail, Node004, Notification: Notification - Node Alive
2019-08-03 10:12:25.934 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:25.934 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:25.937Z z2m:Zwave Notification from node 4: Notification - Node Alive (6)
2019-08-03T10:12:25.938Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:82.387 -> 82.394
2019-08-03T10:12:25.939Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:26.254 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x01, 0x41, 0xda, 0x00, 0x3c, 0x00, 0x01, 0x41, 0xd9, 0xa5, 0x00, 0x08
2019-08-03 10:12:26.254 Detail,
2019-08-03 10:12:26.254 Info, Node004, Received Meter Report for Electric - kWh (1) with Units kWh (0) on Index 0: 82.394
2019-08-03 10:12:26.254 Detail, Node004, Refreshed Value: old value=82.394, new value=82.394, type=decimal
2019-08-03 10:12:26.254 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:26.254 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:26.254 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:26.254 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:26.254 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:26.255Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:82.394 -> 82.394
2019-08-03T10:12:26.256Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:26.434 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x01, 0x41, 0xda, 0x00, 0x3c, 0x00, 0x01, 0x41, 0xd9, 0xa5, 0x00, 0x08
2019-08-03 10:12:26.434 Detail,
2019-08-03 10:12:26.434 Info, Node004, Received Meter Report for Electric - kWh (1) with Units kWh (0) on Index 0: 82.394
2019-08-03 10:12:26.434 Detail, Node004, Refreshed Value: old value=82.394, new value=82.394, type=decimal
2019-08-03 10:12:26.434 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:26.435 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:26.435 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:26.435 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:26.435 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:26.435Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:82.394 -> 82.394
2019-08-03T10:12:26.436Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:28.628 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x01, 0x41, 0xda, 0x00, 0x3c, 0x00, 0x01, 0x41, 0xd9, 0xa4, 0x00, 0x09
2019-08-03 10:12:28.629 Detail,
2019-08-03 10:12:28.629 Info, Node004, Received Meter Report for Electric - kWh (1) with Units kWh (0) on Index 0: 82.394
2019-08-03 10:12:28.629 Detail, Node004, Refreshed Value: old value=82.394, new value=82.394, type=decimal
2019-08-03 10:12:28.629 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:28.629 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:28.629 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:28.629 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:28.629 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:28.629Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:82.394 -> 82.394
2019-08-03T10:12:28.630Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:28.739 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x01, 0x41, 0xda, 0x00, 0x3c, 0x00, 0x01, 0x41, 0xd9, 0xa4, 0x00, 0x09
2019-08-03 10:12:28.739 Detail,
2019-08-03 10:12:28.739 Info, Node004, Received Meter Report for Electric - kWh (1) with Units kWh (0) on Index 0: 82.394
2019-08-03 10:12:28.739 Detail, Node004, Refreshed Value: old value=82.394, new value=82.394, type=decimal
2019-08-03 10:12:28.739 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:28.739 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:28.739 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:28.739 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:28.739 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:28.740Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:82.394 -> 82.394
2019-08-03T10:12:28.741Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:28.850 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x01, 0x41, 0xda, 0x00, 0x3c, 0x00, 0x01, 0x41, 0xd9, 0xa4, 0x00, 0x09
2019-08-03 10:12:28.850 Detail,
2019-08-03 10:12:28.850 Info, Node004, Received Meter Report for Electric - kWh (1) with Units kWh (0) on Index 0: 82.394
2019-08-03 10:12:28.850 Detail, Node004, Refreshed Value: old value=82.394, new value=82.394, type=decimal
2019-08-03 10:12:28.850 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:28.850 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:28.850 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:28.850 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:28.850 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:28.851Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:82.394 -> 82.394
2019-08-03T10:12:28.852Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:30.751 Error, Node002, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-08-03 10:12:30.752 Detail, Node002, Removing current message
2019-08-03 10:12:30.752 Detail, Node002, Notification: Notification - TimeOut
2019-08-03 10:12:30.752 Detail,
2019-08-03 10:12:30.752 Info, Node004, Sending (NoOp) message (Callback ID=0x17, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x17, 0xd1
2019-08-03 10:12:30.752 Info, Node004, Encrypted Flag is 0
2019-08-03T10:12:30.752Z z2m:Zwave Notification from node 2: Notification - TimeOut (1)
2019-08-03 10:12:30.768 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:30.768 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:33.654 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x01, 0x01, 0xdf, 0x00, 0x00, 0xa4, 0x00, 0xf9
2019-08-03 10:12:33.655 Detail,
2019-08-03 10:12:33.655 Info, Node004, Received Meter Report for Electric - W (1) with Units W (2) on Index 2: 66.015
2019-08-03 10:12:33.655 Detail, Node004, Initial read of value
2019-08-03 10:12:33.655 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:33.655 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:33.655 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:33.655 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:33.655Z z2m:Zwave zwave node 4: changed: 50-1-2:Electric - W:64.033 -> 66.015
2019-08-03T10:12:33.656Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:33.762 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x01, 0x01, 0xdf, 0x00, 0x00, 0xa8, 0x00, 0xf5
2019-08-03 10:12:33.762 Detail,
2019-08-03 10:12:33.762 Info, Node004, Received Meter Report for Electric - W (1) with Units W (2) on Index 2: 66.015
2019-08-03 10:12:33.762 Detail, Node004, Refreshed Value: old value=66.015, new value=66.015, type=decimal
2019-08-03 10:12:33.762 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:33.762 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:33.763 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:33.763 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:33.763 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:33.763Z z2m:Zwave zwave node 4: changed: 50-1-2:Electric - W:66.015 -> 66.015
2019-08-03T10:12:33.763Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:33.868 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x64, 0x00, 0x03, 0x86, 0x20, 0x00, 0x00, 0xa5, 0x00, 0x12
2019-08-03 10:12:33.868 Detail,
2019-08-03 10:12:33.868 Info, Node004, Received Meter Report for Electric - V (1) with Units V (4) on Index 4: 230.944
2019-08-03 10:12:33.868 Detail, Node004, Initial read of value
2019-08-03 10:12:33.868 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:33.868 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:33.868 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:33.868 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:33.869Z z2m:Zwave zwave node 4: changed: 50-1-4:Electric - V:228.277 -> 230.944
2019-08-03T10:12:33.870Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:34.025 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x64, 0x00, 0x03, 0x86, 0x20, 0x00, 0x00, 0xa4, 0x00, 0x13
2019-08-03 10:12:34.025 Detail,
2019-08-03 10:12:34.025 Info, Node004, Received Meter Report for Electric - V (1) with Units V (4) on Index 4: 230.944
2019-08-03 10:12:34.025 Detail, Node004, Refreshed Value: old value=230.944, new value=230.944, type=decimal
2019-08-03 10:12:34.025 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:34.025 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:34.025 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:34.025 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:34.025 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:34.025Z z2m:Zwave zwave node 4: changed: 50-1-4:Electric - V:230.944 -> 230.944
2019-08-03T10:12:34.026Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:34.214 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x01, 0x6a, 0x00, 0x00, 0xa2, 0x00, 0xd3
2019-08-03 10:12:34.214 Detail,
2019-08-03 10:12:34.215 Info, Node004, Received Meter Report for Electric - A (1) with Units A (5) on Index 5: 0.362
2019-08-03 10:12:34.215 Detail, Node004, Initial read of value
2019-08-03 10:12:34.215 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:34.215 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:34.215 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:34.215 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:34.215Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.336 -> 0.362
2019-08-03T10:12:34.215Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:34.322 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x01, 0x6a, 0x00, 0x00, 0xa7, 0x00, 0xd6
2019-08-03 10:12:34.322 Detail,
2019-08-03 10:12:34.322 Info, Node004, Received Meter Report for Electric - A (1) with Units A (5) on Index 5: 0.362
2019-08-03 10:12:34.322 Detail, Node004, Refreshed Value: old value=0.362, new value=0.362, type=decimal
2019-08-03 10:12:34.322 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:34.322 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:12:34.322 Detail, Node004, Changes to this value are not verified
2019-08-03 10:12:34.323 Detail, Node004, Notification: ValueChanged
2019-08-03 10:12:34.323 Detail, Node004, Notification: ValueChanged
2019-08-03T10:12:34.323Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.362 -> 0.362
2019-08-03T10:12:34.323Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:12:34.901 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x17, 0x01, 0x01, 0x9e, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x04, 0x00, 0x00, 0x03
2019-08-03 10:12:34.901 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x17 received (expected 0x17)
2019-08-03 10:12:34.901 Detail, Node004, Extended TxStatus: Time: 4140, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 4, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:34.901 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:12:34.902 Warning, Node004, WARNING: Device is not a sleeping node.
2019-08-03 10:12:34.902 Info, Node004, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 0 pending 1
2019-08-03 10:12:34.902 Detail, Node004,   Expected callbackId was received
2019-08-03 10:12:34.902 Detail, Node004,   Expected reply was received
2019-08-03 10:12:34.902 Detail, Node004,   Message transaction complete
2019-08-03 10:12:34.902 Detail,
2019-08-03 10:12:34.902 Detail, Node004, Removing current message
2019-08-03 10:12:34.902 Detail, Node004, Notification: Notification - NoOperation
2019-08-03 10:12:34.902 Detail,
2019-08-03 10:12:34.902 Info, Node002, Sending (Send) message (Callback ID=0x14, Expected Reply=0x04) - SensorMultilevelCmd_Get (Node=2): 0x01, 0x0b, 0x00, 0x13, 0x02, 0x04, 0x31, 0x04, 0x05, 0x00, 0x25, 0x14, 0xe0
2019-08-03 10:12:34.902 Info, Node002, Encrypted Flag is 0
2019-08-03T10:12:34.902Z z2m:Zwave Notification from node 4: Notification - NoOperation (2)
2019-08-03 10:12:34.910 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:34.910 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:39.485 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x14, 0x01, 0x01, 0xca, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x57
2019-08-03 10:12:39.486 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x14 received (expected 0x14)
2019-08-03 10:12:39.486 Detail, Node002, Extended TxStatus: Time: 4580, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:39.486 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:12:39.486 Warning, Node002, WARNING: Device is not a sleeping node.
2019-08-03 10:12:39.486 Detail, Node002,   Expected callbackId was received
2019-08-03 10:12:44.903 Error, Node002, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-08-03 10:12:44.903 Detail, Node002, Removing current message
2019-08-03 10:12:44.903 Detail, Node002, Notification: Notification - TimeOut
2019-08-03 10:12:44.903 Detail,
2019-08-03 10:12:44.903 Info, Node002, Sending (Send) message (Callback ID=0x15, Expected Reply=0x04) - SensorMultilevelCmd_Get (Node=2): 0x01, 0x0b, 0x00, 0x13, 0x02, 0x04, 0x31, 0x04, 0x1b, 0x00, 0x25, 0x15, 0xff
2019-08-03 10:12:44.903 Info, Node002, Encrypted Flag is 0
2019-08-03T10:12:44.903Z z2m:Zwave Notification from node 2: Notification - TimeOut (1)
2019-08-03 10:12:44.918 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:12:44.919 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:12:48.677 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x01, 0x22, 0x00, 0xeb, 0xa5, 0x00, 0xa8
2019-08-03 10:12:48.677 Detail,
2019-08-03 10:12:48.677 Info, Node002, Response RTT 3775 Average Response RTT 2180
2019-08-03 10:12:48.677 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Air Temperature: value=23.5C
2019-08-03 10:12:48.677 Detail, Node002, Refreshed Value: old value=23.5, new value=23.5, type=decimal
2019-08-03 10:12:48.677 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:48.677 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:48.848 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x01, 0x22, 0x00, 0xeb, 0xa4, 0x00, 0xa9
2019-08-03 10:12:48.848 Detail,
2019-08-03 10:12:48.848 Info, Node002, Response RTT 3946 Average Response RTT 3063
2019-08-03 10:12:48.848 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Air Temperature: value=23.5C
2019-08-03 10:12:48.848 Detail, Node002, Refreshed Value: old value=23.5, new value=23.5, type=decimal
2019-08-03 10:12:48.848 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:48.848 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:49.037 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x15, 0x01, 0x01, 0x9d, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x04, 0x00, 0x00, 0x02
2019-08-03 10:12:49.037 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x15 received (expected 0x15)
2019-08-03 10:12:49.038 Detail, Node002, Extended TxStatus: Time: 4130, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 4, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:12:49.038 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:12:49.038 Warning, Node002, WARNING: Device is not a sleeping node.
2019-08-03 10:12:49.038 Detail, Node002,   Expected callbackId was received
2019-08-03 10:12:49.165 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x01, 0x22, 0x00, 0xeb, 0xa5, 0x00, 0xa8
2019-08-03 10:12:49.165 Detail,
2019-08-03 10:12:49.165 Info, Node002, Response RTT 4262 Average Response RTT 3662
2019-08-03 10:12:49.165 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Air Temperature: value=23.5C
2019-08-03 10:12:49.165 Detail, Node002, Refreshed Value: old value=23.5, new value=23.5, type=decimal
2019-08-03 10:12:49.165 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:49.165 Detail, Node002,   Expected reply and command class was received
2019-08-03 10:12:49.165 Detail, Node002,   Message transaction complete
2019-08-03 10:12:49.165 Detail,
2019-08-03 10:12:49.165 Detail, Node002, Removing current message
2019-08-03 10:12:49.165 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:49.165 Detail,
2019-08-03 10:12:49.165 Info, Node002, Sending (Send) message (Callback ID=0x16, Expected Reply=0x04) - BatteryCmd_Get (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x80, 0x02, 0x25, 0x16, 0x54
2019-08-03 10:12:49.165 Info, Node002, Encrypted Flag is 0
2019-08-03 10:12:49.167 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x00, 0xe9
2019-08-03 10:12:49.167 Error, Node002, ERROR: ZW_SEND_DATA could not be delivered to Z-Wave stack
2019-08-03 10:12:49.506 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x01, 0x22, 0x00, 0xeb, 0xa2, 0x00, 0xaf
2019-08-03 10:12:49.506 Detail,
2019-08-03 10:12:49.506 Info, Node002, Response RTT 341 Average Response RTT 2001
2019-08-03 10:12:49.506 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Air Temperature: value=23.5C
2019-08-03 10:12:49.506 Detail, Node002, Refreshed Value: old value=23.5, new value=23.5, type=decimal
2019-08-03 10:12:49.506 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:49.506 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:49.678 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x01, 0x22, 0x00, 0xeb, 0xa4, 0x00, 0xa9
2019-08-03 10:12:49.678 Detail,
2019-08-03 10:12:49.678 Info, Node002, Response RTT 513 Average Response RTT 1257
2019-08-03 10:12:49.678 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Air Temperature: value=23.5C
2019-08-03 10:12:49.678 Detail, Node002, Refreshed Value: old value=23.5, new value=23.5, type=decimal
2019-08-03 10:12:49.678 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:49.678 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:49.929 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x01, 0x22, 0x00, 0xeb, 0xa7, 0x00, 0xaa
2019-08-03 10:12:49.929 Detail,
2019-08-03 10:12:49.929 Info, Node002, Response RTT 764 Average Response RTT 1010
2019-08-03 10:12:49.929 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Air Temperature: value=23.5C
2019-08-03 10:12:49.929 Detail, Node002, Refreshed Value: old value=23.5, new value=23.5, type=decimal
2019-08-03 10:12:49.929 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:49.929 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:54.090 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x05, 0x01, 0x38, 0xa2, 0x00, 0x5b
2019-08-03 10:12:54.090 Detail,
2019-08-03 10:12:54.090 Info, Node002, Response RTT 4925 Average Response RTT 2967
2019-08-03 10:12:54.090 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Humidity: value=56%
2019-08-03 10:12:54.090 Detail, Node002, Initial read of value
2019-08-03 10:12:54.090 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:54.196 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x05, 0x01, 0x38, 0xa5, 0x00, 0x5c
2019-08-03 10:12:54.196 Detail,
2019-08-03 10:12:54.196 Info, Node002, Response RTT 5031 Average Response RTT 3999
2019-08-03 10:12:54.196 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Humidity: value=56%
2019-08-03 10:12:54.196 Detail, Node002, Refreshed Value: old value=56, new value=56, type=decimal
2019-08-03 10:12:54.196 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:54.196 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:55.226 Detail, Node002,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x02, 0x03, 0x80, 0x03, 0x64, 0xa4, 0x00, 0xb2
2019-08-03 10:12:55.226 Detail,
2019-08-03 10:12:55.226 Info, Node002, Response RTT 6060 Average Response RTT 5029
2019-08-03 10:12:55.226 Info, Node002, Received Battery report from node 2: level=100
2019-08-03 10:12:55.226 Detail, Node002, Initial read of value
2019-08-03 10:12:55.226 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:55.540 Detail, Node002,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x02, 0x03, 0x80, 0x03, 0x64, 0xa4, 0x00, 0xb2
2019-08-03 10:12:55.540 Detail,
2019-08-03 10:12:55.540 Info, Node002, Response RTT 6375 Average Response RTT 5702
2019-08-03 10:12:55.540 Info, Node002, Received Battery report from node 2: level=100
2019-08-03 10:12:55.540 Detail, Node002, Refreshed Value: old value=100, new value=100, type=byte
2019-08-03 10:12:55.540 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:55.540 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:55.714 Detail, Node002,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x02, 0x03, 0x80, 0x03, 0x64, 0xa4, 0x00, 0xb2
2019-08-03 10:12:55.715 Detail,
2019-08-03 10:12:55.715 Info, Node002, Response RTT 6549 Average Response RTT 6125
2019-08-03 10:12:55.715 Info, Node002, Received Battery report from node 2: level=100
2019-08-03 10:12:55.715 Detail, Node002, Refreshed Value: old value=100, new value=100, type=byte
2019-08-03 10:12:55.715 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:55.715 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:56.050 Detail, Node002,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x02, 0x03, 0x80, 0x03, 0x64, 0xa5, 0x00, 0xb3
2019-08-03 10:12:56.050 Detail,
2019-08-03 10:12:56.050 Info, Node002, Response RTT 6886 Average Response RTT 6505
2019-08-03 10:12:56.050 Info, Node002, Received Battery report from node 2: level=100
2019-08-03 10:12:56.050 Detail, Node002, Refreshed Value: old value=100, new value=100, type=byte
2019-08-03 10:12:56.050 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:56.050 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:56.295 Detail, Node002,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x02, 0x03, 0x80, 0x03, 0x64, 0xa4, 0x00, 0xb2
2019-08-03 10:12:56.295 Detail,
2019-08-03 10:12:56.295 Info, Node002, Response RTT 7129 Average Response RTT 6817
2019-08-03 10:12:56.295 Info, Node002, Received Battery report from node 2: level=100
2019-08-03 10:12:56.295 Detail, Node002, Refreshed Value: old value=100, new value=100, type=byte
2019-08-03 10:12:56.295 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:56.295 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:56.464 Detail, Node002,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x02, 0x03, 0x80, 0x03, 0x64, 0xa7, 0x00, 0xb1
2019-08-03 10:12:56.464 Detail,
2019-08-03 10:12:56.464 Info, Node002, Response RTT 7299 Average Response RTT 7058
2019-08-03 10:12:56.464 Info, Node002, Received Battery report from node 2: level=100
2019-08-03 10:12:56.464 Detail, Node002, Refreshed Value: old value=100, new value=100, type=byte
2019-08-03 10:12:56.464 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:56.464 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:56.811 Detail, Node002,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x02, 0x03, 0x80, 0x03, 0x64, 0xa4, 0x00, 0xb2
2019-08-03 10:12:56.811 Detail,
2019-08-03 10:12:56.811 Info, Node002, Response RTT 7645 Average Response RTT 7351
2019-08-03 10:12:56.811 Info, Node002, Received Battery report from node 2: level=100
2019-08-03 10:12:56.811 Detail, Node002, Refreshed Value: old value=100, new value=100, type=byte
2019-08-03 10:12:56.811 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:56.811 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:57.829 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x03, 0x0a, 0x00, 0x08, 0xa7, 0x00, 0x63
2019-08-03 10:12:57.829 Detail,
2019-08-03 10:12:57.829 Info, Node002, Response RTT 8664 Average Response RTT 8007
2019-08-03 10:12:57.829 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Illuminance: value=8Lux
2019-08-03 10:12:57.829 Detail, Node002, Initial read of value
2019-08-03 10:12:57.829 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:58.146 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x03, 0x0a, 0x00, 0x08, 0xa2, 0x00, 0x66
2019-08-03 10:12:58.147 Detail,
2019-08-03 10:12:58.147 Info, Node002, Response RTT 8982 Average Response RTT 8494
2019-08-03 10:12:58.147 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Illuminance: value=8Lux
2019-08-03 10:12:58.147 Detail, Node002, Refreshed Value: old value=8, new value=8, type=decimal
2019-08-03 10:12:58.147 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:58.147 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:58.393 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x03, 0x0a, 0x00, 0x08, 0x7d, 0x00, 0xb9
2019-08-03 10:12:58.393 Detail,
2019-08-03 10:12:58.393 Info, Node002, Response RTT 9227 Average Response RTT 8860
2019-08-03 10:12:58.393 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Illuminance: value=8Lux
2019-08-03 10:12:58.393 Detail, Node002, Refreshed Value: old value=8, new value=8, type=decimal
2019-08-03 10:12:58.393 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:58.393 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:58.771 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x03, 0x0a, 0x00, 0x08, 0xa7, 0x00, 0x63
2019-08-03 10:12:58.771 Detail,
2019-08-03 10:12:58.771 Info, Node002, Response RTT 9606 Average Response RTT 9233
2019-08-03 10:12:58.771 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Illuminance: value=8Lux
2019-08-03 10:12:58.771 Detail, Node002, Refreshed Value: old value=8, new value=8, type=decimal
2019-08-03 10:12:58.771 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:58.771 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:58.948 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x03, 0x0a, 0x00, 0x08, 0xa5, 0x00, 0x61
2019-08-03 10:12:58.948 Detail,
2019-08-03 10:12:58.948 Info, Node002, Response RTT 9783 Average Response RTT 9508
2019-08-03 10:12:58.948 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Illuminance: value=8Lux
2019-08-03 10:12:58.948 Detail, Node002, Refreshed Value: old value=8, new value=8, type=decimal
2019-08-03 10:12:58.948 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:58.948 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:59.120 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x03, 0x0a, 0x00, 0x08, 0xa5, 0x00, 0x61
2019-08-03 10:12:59.120 Detail,
2019-08-03 10:12:59.120 Info, Node002, Response RTT 9956 Average Response RTT 9732
2019-08-03 10:12:59.120 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Illuminance: value=8Lux
2019-08-03 10:12:59.120 Detail, Node002, Refreshed Value: old value=8, new value=8, type=decimal
2019-08-03 10:12:59.120 Detail, Node002, Changes to this value are not verified
2019-08-03 10:12:59.120 Detail, Node002, Notification: ValueChanged
2019-08-03 10:12:59.164 Error, Node002, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-08-03 10:12:59.164 Detail, Node002, Removing current message
2019-08-03 10:12:59.165 Detail, Node002, Notification: Notification - TimeOut
2019-08-03 10:12:59.165 Detail, Node002, Query Stage Complete (Dynamic)
2019-08-03 10:12:59.165 Detail, Node002, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2019-08-03 10:12:59.165 Detail, Node002, QueryStage_Configuration
2019-08-03 10:12:59.165 Detail, Node002, QueryStage_Complete
2019-08-03 10:12:59.165 Detail, Node002, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x18, 0x54
2019-08-03 10:12:59.165 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2019-08-03T10:12:59.165Z z2m:Zwave Notification from node 2: Notification - TimeOut (1)
2019-08-03 10:12:59.165 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0
2019-08-03 10:12:59.165 Info, Saving Cache
2019-08-03 10:12:59.165 Info, Node001, Cache Save for Node 1 as its QueryStage_CacheLoad
2019-08-03 10:12:59.166 Info, Node002, Cache Save for Node 2 as its QueryStage_CacheLoad
2019-08-03 10:12:59.167 Info, Node004, Cache Save for Node 4 as its QueryStage_CacheLoad
2019-08-03 10:12:59.174 Detail, Node002, Notification: NodeQueriesComplete
2019-08-03 10:12:59.174 Detail,
2019-08-03 10:12:59.174 Info, Node002, Sending (WakeUp) message (Callback ID=0x18, Expected Reply=0x13) - WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x18, 0x54
2019-08-03 10:12:59.174 Info, Node002, Encrypted Flag is 0
2019-08-03 10:12:59.176 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x00, 0xe9
2019-08-03 10:12:59.176 Error, Node002, ERROR: ZW_SEND_DATA could not be delivered to Z-Wave stack
2019-08-03T10:12:59.199Z z2m:Zwave node 2 ready: AEON Labs - ZW100 MultiSensor 6 (Home Security Sensor)
2019-08-03 10:13:00.141 Detail, Node002,   Received: 0x01, 0x0e, 0x00, 0x04, 0x00, 0x02, 0x06, 0x31, 0x05, 0x03, 0x0a, 0x00, 0x08, 0x7d, 0x00, 0xb9
2019-08-03 10:13:00.142 Detail,
2019-08-03 10:13:00.142 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Illuminance: value=8Lux
2019-08-03 10:13:00.142 Detail, Node002, Refreshed Value: old value=8, new value=8, type=decimal
2019-08-03 10:13:00.142 Detail, Node002, Changes to this value are not verified
2019-08-03 10:13:00.142 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:00.142Z z2m:Zwave zwave node 2: changed: 49-1-3:Illuminance:8 -> 8
2019-08-03 10:13:01.050 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x1b, 0x01, 0x00, 0xa5, 0x00, 0x7a
2019-08-03 10:13:01.050 Detail,
2019-08-03 10:13:01.050 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Ultraviolet: value=0UV
2019-08-03 10:13:01.050 Detail, Node002, Initial read of value
2019-08-03 10:13:01.050 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:01.051Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-08-03 10:13:01.295 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x1b, 0x01, 0x00, 0xa5, 0x00, 0x7a
2019-08-03 10:13:01.296 Detail,
2019-08-03 10:13:01.296 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Ultraviolet: value=0UV
2019-08-03 10:13:01.296 Detail, Node002, Refreshed Value: old value=0, new value=0, type=decimal
2019-08-03 10:13:01.296 Detail, Node002, Changes to this value are not verified
2019-08-03 10:13:01.296 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:01.296Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-08-03 10:13:01.611 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x1b, 0x01, 0x00, 0xa2, 0x00, 0x7d
2019-08-03 10:13:01.611 Detail,
2019-08-03 10:13:01.611 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Ultraviolet: value=0UV
2019-08-03 10:13:01.611 Detail, Node002, Refreshed Value: old value=0, new value=0, type=decimal
2019-08-03 10:13:01.611 Detail, Node002, Changes to this value are not verified
2019-08-03 10:13:01.611 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:01.612Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-08-03 10:13:01.951 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x1b, 0x01, 0x00, 0xa5, 0x00, 0x7a
2019-08-03 10:13:01.951 Detail,
2019-08-03 10:13:01.951 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Ultraviolet: value=0UV
2019-08-03 10:13:01.951 Detail, Node002, Refreshed Value: old value=0, new value=0, type=decimal
2019-08-03 10:13:01.951 Detail, Node002, Changes to this value are not verified
2019-08-03 10:13:01.951 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:01.952Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-08-03 10:13:02.052 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x1b, 0x01, 0x00, 0xa4, 0x00, 0x7b
2019-08-03 10:13:02.052 Detail,
2019-08-03 10:13:02.052 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Ultraviolet: value=0UV
2019-08-03 10:13:02.052 Detail, Node002, Refreshed Value: old value=0, new value=0, type=decimal
2019-08-03 10:13:02.052 Detail, Node002, Changes to this value are not verified
2019-08-03 10:13:02.052 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:02.053Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-08-03 10:13:02.157 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x1b, 0x01, 0x00, 0xa5, 0x00, 0x7a
2019-08-03 10:13:02.158 Detail,
2019-08-03 10:13:02.158 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Ultraviolet: value=0UV
2019-08-03 10:13:02.158 Detail, Node002, Refreshed Value: old value=0, new value=0, type=decimal
2019-08-03 10:13:02.158 Detail, Node002, Changes to this value are not verified
2019-08-03 10:13:02.158 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:02.158Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-08-03 10:13:02.487 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x1b, 0x01, 0x00, 0x7d, 0x00, 0xa2
2019-08-03 10:13:02.487 Detail,
2019-08-03 10:13:02.487 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Ultraviolet: value=0UV
2019-08-03 10:13:02.487 Detail, Node002, Refreshed Value: old value=0, new value=0, type=decimal
2019-08-03 10:13:02.487 Detail, Node002, Changes to this value are not verified
2019-08-03 10:13:02.487 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:02.488Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-08-03 10:13:03.007 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x1b, 0x01, 0x00, 0x7d, 0x00, 0xa2
2019-08-03 10:13:03.008 Detail,
2019-08-03 10:13:03.008 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Ultraviolet: value=0UV
2019-08-03 10:13:03.008 Detail, Node002, Refreshed Value: old value=0, new value=0, type=decimal
2019-08-03 10:13:03.008 Detail, Node002, Changes to this value are not verified
2019-08-03 10:13:03.008 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:03.009Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-08-03 10:13:03.101 Detail, Node002,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x02, 0x05, 0x31, 0x05, 0x1b, 0x01, 0x00, 0xa2, 0x00, 0x7d
2019-08-03 10:13:03.101 Detail,
2019-08-03 10:13:03.101 Info, Node002, Received SensorMultiLevel report from node 2, instance 1, Ultraviolet: value=0UV
2019-08-03 10:13:03.101 Detail, Node002, Refreshed Value: old value=0, new value=0, type=decimal
2019-08-03 10:13:03.101 Detail, Node002, Changes to this value are not verified
2019-08-03 10:13:03.101 Detail, Node002, Notification: ValueChanged
2019-08-03T10:13:03.101Z z2m:Zwave zwave node 2: changed: 49-1-27:Ultraviolet:0 -> 0
2019-08-03 10:13:03.428 Detail, Node002,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x02, 0x02, 0x84, 0x07, 0xa4, 0x00, 0xd6
2019-08-03 10:13:03.428 Detail,
2019-08-03 10:13:03.428 Info, Node002, Received Wakeup Notification from node 2
2019-08-03 10:13:03.428 Detail, Node002, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x19, 0x55
2019-08-03 10:13:03.602 Detail, Node002,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x02, 0x02, 0x84, 0x07, 0xa4, 0x00, 0xd6
2019-08-03 10:13:03.602 Detail,
2019-08-03 10:13:03.602 Info, Node002, Received Wakeup Notification from node 2
2019-08-03 10:13:03.602 Detail, Node002, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x1a, 0x56
2019-08-03 10:13:03.775 Detail, Node002,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x02, 0x02, 0x84, 0x07, 0xa5, 0x00, 0xd7
2019-08-03 10:13:03.775 Detail,
2019-08-03 10:13:03.775 Info, Node002, Received Wakeup Notification from node 2
2019-08-03 10:13:03.775 Detail, Node002, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x1b, 0x57
2019-08-03 10:13:04.109 Detail, Node002,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x02, 0x02, 0x84, 0x07, 0xa2, 0x00, 0xd0
2019-08-03 10:13:04.109 Detail,
2019-08-03 10:13:04.109 Info, Node002, Received Wakeup Notification from node 2
2019-08-03 10:13:04.110 Detail, Node002, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x1c, 0x50
2019-08-03 10:13:04.220 Detail, Node002,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x02, 0x02, 0x84, 0x07, 0xa4, 0x00, 0xd6
2019-08-03 10:13:04.220 Detail,
2019-08-03 10:13:04.220 Info, Node002, Received Wakeup Notification from node 2
2019-08-03 10:13:04.220 Detail, Node002, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x1d, 0x51
2019-08-03 10:13:04.463 Detail, Node002,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x02, 0x02, 0x84, 0x07, 0xa5, 0x00, 0xd7
2019-08-03 10:13:04.463 Detail,
2019-08-03 10:13:04.463 Info, Node002, Received Wakeup Notification from node 2
2019-08-03 10:13:04.463 Detail, Node002, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x1e, 0x52
2019-08-03 10:13:05.367 Detail, Node002,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x02, 0x02, 0x84, 0x07, 0xa2, 0x00, 0xd0
2019-08-03 10:13:05.368 Detail,
2019-08-03 10:13:05.368 Info, Node002, Received Wakeup Notification from node 2
2019-08-03 10:13:05.368 Detail, Node002, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x1f, 0x53
2019-08-03 10:13:05.677 Detail, Node002,   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x02, 0x02, 0x84, 0x07, 0xa2, 0x00, 0xd0
2019-08-03 10:13:05.677 Detail,
2019-08-03 10:13:05.677 Info, Node002, Received Wakeup Notification from node 2
2019-08-03 10:13:05.678 Detail, Node002, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x20, 0x6c
2019-08-03 10:13:09.175 Error, Node002, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-08-03 10:13:09.175 Detail, Node002, Removing current message
2019-08-03 10:13:09.175 Detail, Node002, Notification: Notification - TimeOut
2019-08-03 10:13:09.175 Detail,
2019-08-03 10:13:09.175 Info, Node002, Sending (WakeUp) message (Callback ID=0x19, Expected Reply=0x13) - WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x19, 0x55
2019-08-03 10:13:09.175 Info, Node002, Encrypted Flag is 0
2019-08-03T10:13:09.176Z z2m:Zwave Notification from node 2: Notification - TimeOut (1)
2019-08-03 10:13:09.183 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:13:09.183 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:13:13.640 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x19, 0x01, 0x01, 0xbe, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x2e
2019-08-03 10:13:13.640 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x19 received (expected 0x19)
2019-08-03 10:13:13.640 Detail, Node002, Extended TxStatus: Time: 4460, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:13:13.640 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:13:13.640 Warning, Node002, WARNING: Device is not a sleeping node.
2019-08-03 10:13:13.640 Detail, Node002,   Expected callbackId was received
2019-08-03 10:13:13.640 Detail, Node002,   Expected reply was received
2019-08-03 10:13:13.640 Detail, Node002,   Message transaction complete
2019-08-03 10:13:13.640 Detail,
2019-08-03 10:13:13.640 Detail, Node002, Removing current message
2019-08-03 10:13:13.640 Detail,
2019-08-03 10:13:13.640 Info, Node002, Sending (WakeUp) message (Callback ID=0x1a, Expected Reply=0x13) - WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x1a, 0x56
2019-08-03 10:13:13.640 Info, Node002, Encrypted Flag is 0
2019-08-03 10:13:13.648 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:13:13.648 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:13:18.276 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x1a, 0x01, 0x01, 0xcf, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x5c
2019-08-03 10:13:18.276 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x1a received (expected 0x1a)
2019-08-03 10:13:18.276 Detail, Node002, Extended TxStatus: Time: 4630, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:13:18.276 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:13:18.276 Warning, Node002, WARNING: Device is not a sleeping node.
2019-08-03 10:13:18.276 Detail, Node002,   Expected callbackId was received
2019-08-03 10:13:18.276 Detail, Node002,   Expected reply was received
2019-08-03 10:13:18.276 Detail, Node002,   Message transaction complete
2019-08-03 10:13:18.276 Detail,
2019-08-03 10:13:18.276 Detail, Node002, Removing current message
2019-08-03 10:13:18.276 Detail,
2019-08-03 10:13:18.276 Info, Node002, Sending (WakeUp) message (Callback ID=0x1b, Expected Reply=0x13) - WakeUpCmd_NoMoreInformation (Node=2): 0x01, 0x09, 0x00, 0x13, 0x02, 0x02, 0x84, 0x08, 0x25, 0x1b, 0x57
2019-08-03 10:13:18.276 Info, Node002, Encrypted Flag is 0
2019-08-03 10:13:18.292 Detail, Node002,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:13:18.292 Detail, Node002,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:13:22.409 Detail, Node002,   Received: 0x01, 0x18, 0x00, 0x13, 0x1b, 0x01, 0x01, 0x9c, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x04, 0x00, 0x00, 0x0d
2019-08-03 10:13:22.410 Detail, Node002,   ZW_SEND_DATA Request with callback ID 0x1b received (expected 0x1b)
2019-08-03 10:13:22.410 Detail, Node002, Extended TxStatus: Time: 4120, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 4, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:13:22.410 Info, Node002, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:13:22.410 Warning, Node002, WARNING: Device is not a sleeping node.
2019-08-03 10:13:22.410 Error, Node002, ERROR: node presumed dead
2019-08-03 10:13:22.410 Detail, Node002,   Expected callbackId was received
2019-08-03 10:13:22.410 Detail, Node002,   Expected reply was received
2019-08-03 10:13:22.410 Detail, Node002,   Message transaction complete
2019-08-03 10:13:22.410 Detail,
2019-08-03 10:13:22.410 Detail, Node002, Removing current message
2019-08-03 10:13:22.410 Detail, Node002, Notification: Notification - Node Dead
2019-08-03 10:13:22.410 Error, Node002, ERROR: Dropping command because node is presumed dead
2019-08-03 10:13:22.410 Detail, Node002, Removing current message
2019-08-03 10:13:22.410 Error, Node002, ERROR: Dropping command because node is presumed dead
2019-08-03 10:13:22.410 Detail, Node002, Removing current message
2019-08-03 10:13:22.410 Error, Node002, ERROR: Dropping command because node is presumed dead
2019-08-03 10:13:22.411 Detail, Node002, Removing current message
2019-08-03 10:13:22.411 Error, Node002, ERROR: Dropping command because node is presumed dead
2019-08-03 10:13:22.411 Detail, Node002, Removing current message
2019-08-03 10:13:22.411 Error, Node002, ERROR: Dropping command because node is presumed dead
2019-08-03 10:13:22.411 Detail, Node002, Removing current message
2019-08-03 10:13:22.411 Detail, Node004, Query Stage Complete (CacheLoad)
2019-08-03 10:13:22.411 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=1 queryStage=CacheLoad live=1
2019-08-03 10:13:22.411 Detail, Node004, QueryStage_CacheLoad
2019-08-03 10:13:22.411 Info, Node004, Loading Cache for node 4: Manufacturer=AEON Labs, Product=ZW116 Nano Switch
2019-08-03 10:13:22.411 Info, Node004, Node Identity Codes: 0086:0003:0074
2019-08-03 10:13:22.411 Info, Node004, NoOperation::Set - Routing=true
2019-08-03 10:13:22.411 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x21, 0xe7
2019-08-03 10:13:22.411 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2019-08-03 10:13:22.411 Detail,
2019-08-03 10:13:22.411 Info, Node004, Sending (NoOp) message (Callback ID=0x21, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x21, 0xe7
2019-08-03 10:13:22.411 Info, Node004, Encrypted Flag is 0
2019-08-03T10:13:22.413Z z2m:Zwave Notification from node 2: Notification - Node Dead (5)
2019-08-03 10:13:22.419 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:13:22.419 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
GET / 200 11.374 ms - 1432
GET /static/js/manifest.2ae2e69a05c33dfc65f8.js 200 2.384 ms - 857
GET /static/js/app.dc8fc3d4640c8c162e30.js 200 2.808 ms - 59071
GET /static/css/app.6767e8280089a963b7ff2532eaea9424.css 200 1.809 ms - 210253
GET /static/js/vendor.de2acf90813e187bf2f9.js 200 3.011 ms - 831086
GET /static/logo.png 200 0.970 ms - 10014
2019-08-03T10:13:24.788Z z2m:App New connection ZTZ7a-B_8xYMmF-AAAAA
GET /static/favicons/apple-touch-icon.png 200 2.201 ms - 7413
GET /static/favicons/favicon-32x32.png 200 4.020 ms - 1831
2019-08-03 10:13:25.898 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x01, 0x41, 0xdb, 0x00, 0x3c, 0x00, 0x01, 0x41, 0xda, 0xa4, 0x00, 0x0b
2019-08-03 10:13:25.898 Detail,
2019-08-03 10:13:25.898 Info, Node004, Received Meter Report for Electric - kWh (1) with Units kWh (0) on Index 0: 82.395
2019-08-03 10:13:25.899 Detail, Node004, Refreshed Value: old value=82.394, new value=82.395, type=decimal
2019-08-03 10:13:25.899 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:25.899 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:25.899 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:25.899 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:25.899 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:25.899Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:82.394 -> 82.395
2019-08-03T10:13:25.901Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:26.149 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x01, 0x41, 0xdb, 0x00, 0x3c, 0x00, 0x01, 0x41, 0xda, 0xa5, 0x00, 0x0a
2019-08-03 10:13:26.149 Detail,
2019-08-03 10:13:26.149 Info, Node004, Received Meter Report for Electric - kWh (1) with Units kWh (0) on Index 0: 82.395
2019-08-03 10:13:26.149 Detail, Node004, Refreshed Value: old value=82.395, new value=82.395, type=decimal
2019-08-03 10:13:26.149 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.149 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:26.149 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.149 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:26.149 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:26.151Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:82.395 -> 82.395
2019-08-03T10:13:26.153Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:26.330 Detail, Node004,   Received: 0x01, 0x16, 0x00, 0x04, 0x00, 0x04, 0x0e, 0x32, 0x02, 0x21, 0x64, 0x00, 0x01, 0x41, 0xdb, 0x00, 0x3c, 0x00, 0x01, 0x41, 0xda, 0xa7, 0x00, 0x08
2019-08-03 10:13:26.330 Detail,
2019-08-03 10:13:26.330 Info, Node004, Received Meter Report for Electric - kWh (1) with Units kWh (0) on Index 0: 82.395
2019-08-03 10:13:26.330 Detail, Node004, Refreshed Value: old value=82.395, new value=82.395, type=decimal
2019-08-03 10:13:26.330 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.330 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:26.330 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.330 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:26.330 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:26.331Z z2m:Zwave zwave node 4: changed: 50-1-0:Electric - kWh:82.395 -> 82.395
2019-08-03T10:13:26.332Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:26.578 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x01, 0x27, 0x36, 0x00, 0x00, 0xa5, 0x00, 0x37
2019-08-03 10:13:26.578 Detail,
2019-08-03 10:13:26.578 Info, Node004, Received Meter Report for Electric - W (1) with Units W (2) on Index 2: 75.574
2019-08-03 10:13:26.578 Detail, Node004, Refreshed Value: old value=66.015, new value=75.574, type=decimal
2019-08-03 10:13:26.578 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.578 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:26.578 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.578 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:26.578 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:26.579Z z2m:Zwave zwave node 4: changed: 50-1-2:Electric - W:66.015 -> 75.574
2019-08-03T10:13:26.580Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:26.712 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x21, 0x01, 0x01, 0xad, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x05
2019-08-03 10:13:26.712 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x21 received (expected 0x21)
2019-08-03 10:13:26.712 Detail, Node004, Extended TxStatus: Time: 4290, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:13:26.712 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:13:26.712 Warning, Node004, WARNING: Device is not a sleeping node.
2019-08-03 10:13:26.712 Info, Node004, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 1 pending 1
2019-08-03 10:13:26.712 Detail, Node004,   Expected callbackId was received
2019-08-03 10:13:26.712 Detail, Node004,   Expected reply was received
2019-08-03 10:13:26.712 Detail, Node004,   Message transaction complete
2019-08-03 10:13:26.712 Detail,
2019-08-03 10:13:26.712 Detail, Node004, Removing current message
2019-08-03 10:13:26.712 Detail, Node004, Notification: Notification - NoOperation
2019-08-03 10:13:26.712 Detail, Node004, Query Stage Complete (CacheLoad)
2019-08-03 10:13:26.712 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=2 queryStage=CacheLoad live=1
2019-08-03 10:13:26.712 Detail, Node004, QueryStage_CacheLoad
2019-08-03 10:13:26.712 Info, Node004, Loading Cache for node 4: Manufacturer=AEON Labs, Product=ZW116 Nano Switch
2019-08-03 10:13:26.712 Info, Node004, Node Identity Codes: 0086:0003:0074
2019-08-03 10:13:26.713 Info, Node004, NoOperation::Set - Routing=true
2019-08-03 10:13:26.713 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x22, 0xe4
2019-08-03 10:13:26.713 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2019-08-03 10:13:26.713 Detail,
2019-08-03 10:13:26.713 Info, Node004, Sending (NoOp) message (Callback ID=0x22, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x22, 0xe4
2019-08-03 10:13:26.713 Info, Node004, Encrypted Flag is 0
2019-08-03T10:13:26.713Z z2m:Zwave Notification from node 4: Notification - NoOperation (2)
2019-08-03 10:13:26.720 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:13:26.720 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:13:26.756 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x01, 0x27, 0x36, 0x00, 0x00, 0xa5, 0x00, 0x37
2019-08-03 10:13:26.756 Detail,
2019-08-03 10:13:26.756 Info, Node004, Received Meter Report for Electric - W (1) with Units W (2) on Index 2: 75.574
2019-08-03 10:13:26.756 Detail, Node004, Refreshed Value: old value=75.574, new value=75.574, type=decimal
2019-08-03 10:13:26.756 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.756 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:26.756 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.756 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:26.756 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:26.757Z z2m:Zwave zwave node 4: changed: 50-1-2:Electric - W:75.574 -> 75.574
2019-08-03T10:13:26.758Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:26.933 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0x21, 0x74, 0x00, 0x01, 0x27, 0x36, 0x00, 0x00, 0xa5, 0x00, 0x37
2019-08-03 10:13:26.933 Detail,
2019-08-03 10:13:26.933 Info, Node004, Received Meter Report for Electric - W (1) with Units W (2) on Index 2: 75.574
2019-08-03 10:13:26.933 Detail, Node004, Refreshed Value: old value=75.574, new value=75.574, type=decimal
2019-08-03 10:13:26.933 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.933 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:26.933 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:26.933 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:26.933 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:26.934Z z2m:Zwave zwave node 4: changed: 50-1-2:Electric - W:75.574 -> 75.574
2019-08-03T10:13:26.936Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:27.091 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x64, 0x00, 0x03, 0x84, 0x4c, 0x00, 0x00, 0xa4, 0x00, 0x7d
2019-08-03 10:13:27.091 Detail,
2019-08-03 10:13:27.091 Info, Node004, Received Meter Report for Electric - V (1) with Units V (4) on Index 4: 230.476
2019-08-03 10:13:27.091 Detail, Node004, Refreshed Value: old value=230.944, new value=230.476, type=decimal
2019-08-03 10:13:27.091 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.091 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:27.091 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.091 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:27.091 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:27.092Z z2m:Zwave zwave node 4: changed: 50-1-4:Electric - V:230.944 -> 230.476
2019-08-03T10:13:27.094Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:27.408 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x64, 0x00, 0x03, 0x84, 0x4c, 0x00, 0x00, 0xa5, 0x00, 0x7c
2019-08-03 10:13:27.408 Detail,
2019-08-03 10:13:27.408 Info, Node004, Received Meter Report for Electric - V (1) with Units V (4) on Index 4: 230.476
2019-08-03 10:13:27.408 Detail, Node004, Refreshed Value: old value=230.476, new value=230.476, type=decimal
2019-08-03 10:13:27.408 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.408 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:27.408 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.408 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:27.408 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:27.410Z z2m:Zwave zwave node 4: changed: 50-1-4:Electric - V:230.476 -> 230.476
2019-08-03T10:13:27.411Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:27.430 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x01, 0x3c, 0x00, 0x00, 0xa5, 0x00, 0x82
2019-08-03 10:13:27.430 Detail,
2019-08-03 10:13:27.430 Info, Node004, Received Meter Report for Electric - A (1) with Units A (5) on Index 5: 0.316
2019-08-03 10:13:27.430 Detail, Node004, Refreshed Value: old value=0.362, new value=0.316, type=decimal
2019-08-03 10:13:27.430 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.430 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:27.430 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.430 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:27.430 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:27.431Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.362 -> 0.316
2019-08-03T10:13:27.432Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:27.640 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x01, 0x7c, 0x00, 0x00, 0xa4, 0x00, 0xc3
2019-08-03 10:13:27.640 Detail,
2019-08-03 10:13:27.640 Info, Node004, Received Meter Report for Electric - A (1) with Units A (5) on Index 5: 0.380
2019-08-03 10:13:27.640 Detail, Node004, Refreshed Value: old value=0.316, new value=0.380, type=decimal
2019-08-03 10:13:27.640 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.640 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:27.640 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.640 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:27.640 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:27.641Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.316 -> 0.38
2019-08-03T10:13:27.643Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:27.747 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x01, 0x7c, 0x00, 0x00, 0xa4, 0x00, 0xc3
2019-08-03 10:13:27.747 Detail,
2019-08-03 10:13:27.747 Info, Node004, Received Meter Report for Electric - A (1) with Units A (5) on Index 5: 0.380
2019-08-03 10:13:27.747 Detail, Node004, Refreshed Value: old value=0.380, new value=0.380, type=decimal
2019-08-03 10:13:27.747 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.747 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:27.747 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.747 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:27.747 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:27.748Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.38 -> 0.38
2019-08-03T10:13:27.748Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:27.855 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x01, 0x7c, 0x00, 0x00, 0xa7, 0x00, 0xc0
2019-08-03 10:13:27.855 Detail,
2019-08-03 10:13:27.855 Info, Node004, Received Meter Report for Electric - A (1) with Units A (5) on Index 5: 0.380
2019-08-03 10:13:27.855 Detail, Node004, Refreshed Value: old value=0.380, new value=0.380, type=decimal
2019-08-03 10:13:27.855 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.855 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:27.855 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:27.855 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:27.855 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:27.855Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.38 -> 0.38
2019-08-03T10:13:27.856Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:30.663 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x01, 0x7c, 0x00, 0x00, 0xa4, 0x00, 0xc3
2019-08-03 10:13:30.663 Detail,
2019-08-03 10:13:30.663 Info, Node004, Received Meter Report for Electric - A (1) with Units A (5) on Index 5: 0.380
2019-08-03 10:13:30.663 Detail, Node004, Refreshed Value: old value=0.380, new value=0.380, type=decimal
2019-08-03 10:13:30.663 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:30.663 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:30.664 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:30.664 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:30.664 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:30.664Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.38 -> 0.38
2019-08-03T10:13:30.666Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:30.840 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x01, 0x7c, 0x00, 0x00, 0x7d, 0x00, 0x1a
2019-08-03 10:13:30.841 Detail,
2019-08-03 10:13:30.841 Info, Node004, Received Meter Report for Electric - A (1) with Units A (5) on Index 5: 0.380
2019-08-03 10:13:30.841 Detail, Node004, Refreshed Value: old value=0.380, new value=0.380, type=decimal
2019-08-03 10:13:30.841 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:30.841 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:30.841 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:30.841 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:30.841 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:30.843Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.38 -> 0.38
2019-08-03T10:13:30.844Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03 10:13:30.986 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x22, 0x01, 0x01, 0xab, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x00
2019-08-03 10:13:30.986 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x22 received (expected 0x22)
2019-08-03 10:13:30.986 Detail, Node004, Extended TxStatus: Time: 4270, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:13:30.986 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:13:30.986 Warning, Node004, WARNING: Device is not a sleeping node.
2019-08-03 10:13:30.986 Info, Node004, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 2 pending 1
2019-08-03 10:13:30.986 Detail, Node004,   Expected callbackId was received
2019-08-03 10:13:30.987 Detail, Node004,   Expected reply was received
2019-08-03 10:13:30.987 Detail, Node004,   Message transaction complete
2019-08-03 10:13:30.987 Detail,
2019-08-03 10:13:30.987 Detail, Node004, Removing current message
2019-08-03 10:13:30.987 Detail, Node004, Notification: Notification - NoOperation
2019-08-03 10:13:30.987 Detail, Node004, Query Stage Complete (CacheLoad)
2019-08-03 10:13:30.987 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2019-08-03 10:13:30.987 Detail, Node004, QueryStage_CacheLoad
2019-08-03 10:13:30.987 Info, Node004, Loading Cache for node 4: Manufacturer=AEON Labs, Product=ZW116 Nano Switch
2019-08-03 10:13:30.987 Info, Node004, Node Identity Codes: 0086:0003:0074
2019-08-03 10:13:30.987 Info, Node004, NoOperation::Set - Routing=true
2019-08-03 10:13:30.987 Detail, Node004, Queuing (NoOp) NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x23, 0xe5
2019-08-03 10:13:30.987 Detail, Node004, Queuing (Query) Query Stage Complete (CacheLoad)
2019-08-03 10:13:30.987 Detail,
2019-08-03 10:13:30.987 Info, Node004, Sending (NoOp) message (Callback ID=0x23, Expected Reply=0x13) - NoOperation_Set (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x00, 0x00, 0x25, 0x23, 0xe5
2019-08-03 10:13:30.987 Info, Node004, Encrypted Flag is 0
2019-08-03T10:13:30.987Z z2m:Zwave Notification from node 4: Notification - NoOperation (2)
2019-08-03 10:13:30.995 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:13:30.995 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:13:31.158 Detail, Node004,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x04, 0x0a, 0x32, 0x02, 0xa1, 0x6c, 0x00, 0x00, 0x01, 0x7c, 0x00, 0x00, 0xa4, 0x00, 0xc3
2019-08-03 10:13:31.158 Detail,
2019-08-03 10:13:31.158 Info, Node004, Received Meter Report for Electric - A (1) with Units A (5) on Index 5: 0.380
2019-08-03 10:13:31.158 Detail, Node004, Refreshed Value: old value=0.380, new value=0.380, type=decimal
2019-08-03 10:13:31.158 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:31.158 Detail, Node004, Refreshed Value: old value=false, new value=false, type=bool
2019-08-03 10:13:31.158 Detail, Node004, Changes to this value are not verified
2019-08-03 10:13:31.158 Detail, Node004, Notification: ValueChanged
2019-08-03 10:13:31.158 Detail, Node004, Notification: ValueChanged
2019-08-03T10:13:31.159Z z2m:Zwave zwave node 4: changed: 50-1-5:Electric - A:0.38 -> 0.38
2019-08-03T10:13:31.160Z z2m:Zwave zwave node 4: changed: 50-1-256:Exporting:false -> false
2019-08-03T10:13:33.706Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, false ]
2019-08-03 10:13:33.713 Info, Node004, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2019-08-03 10:13:33.713 Info, Node004, SwitchBinary::Set - Setting to Off
2019-08-03 10:13:33.713 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x24, 0xc4
2019-08-03 10:13:33.713 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x25, 0xc4
2019-08-03T10:13:33.713Z z2m:Zwave Success zwave api call setValue
2019-08-03 10:13:35.312 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x23, 0x01, 0x01, 0xb0, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x1a
2019-08-03 10:13:35.312 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x23 received (expected 0x23)
2019-08-03 10:13:35.312 Detail, Node004, Extended TxStatus: Time: 4320, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:13:35.312 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:13:35.312 Warning, Node004, WARNING: Device is not a sleeping node.
2019-08-03 10:13:35.312 Info, Node004, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 0 pending 1
2019-08-03 10:13:35.312 Detail, Node004,   Expected callbackId was received
2019-08-03 10:13:35.312 Detail, Node004,   Expected reply was received
2019-08-03 10:13:35.312 Detail, Node004,   Message transaction complete
2019-08-03 10:13:35.312 Detail,
2019-08-03 10:13:35.313 Detail, Node004, Removing current message
2019-08-03 10:13:35.313 Detail, Node004, Notification: Notification - NoOperation
2019-08-03 10:13:35.313 Detail,
2019-08-03 10:13:35.313 Info, Node004, Sending (Send) message (Callback ID=0x24, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0x00, 0x25, 0x24, 0xc4
2019-08-03 10:13:35.313 Info, Node004, Encrypted Flag is 0
2019-08-03T10:13:35.313Z z2m:Zwave Notification from node 4: Notification - NoOperation (2)
2019-08-03 10:13:35.320 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:13:35.321 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03 10:13:39.576 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x24, 0x01, 0x01, 0xaa, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x07, 0x00, 0x00, 0x07
2019-08-03 10:13:39.576 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x24 received (expected 0x24)
2019-08-03 10:13:39.576 Detail, Node004, Extended TxStatus: Time: 4260, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 7, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:13:39.576 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:13:39.576 Warning, Node004, WARNING: Device is not a sleeping node.
2019-08-03 10:13:39.576 Detail, Node004,   Expected callbackId was received
2019-08-03 10:13:39.576 Detail, Node004,   Expected reply was received
2019-08-03 10:13:39.576 Detail, Node004,   Message transaction complete
2019-08-03 10:13:39.576 Detail,
2019-08-03 10:13:39.576 Detail, Node004, Removing current message
2019-08-03 10:13:39.577 Detail,
2019-08-03 10:13:39.577 Info, Node004, Sending (Send) message (Callback ID=0x25, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x25, 0xc4
2019-08-03 10:13:39.577 Info, Node004, Encrypted Flag is 0
2019-08-03 10:13:39.592 Detail, Node004,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-08-03 10:13:39.592 Detail, Node004,   ZW_SEND_DATA delivered to Z-Wave stack
2019-08-03T10:13:41.469Z z2m:App Zwave api call: setValue [ 4, 37, 1, 0, true ]
2019-08-03 10:13:41.470 Info, Node004, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - True
2019-08-03 10:13:41.470 Info, Node004, SwitchBinary::Set - Setting to On
2019-08-03 10:13:41.470 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Set (Node=4): 0x01, 0x0a, 0x00, 0x13, 0x04, 0x03, 0x25, 0x01, 0xff, 0x25, 0x26, 0x39
2019-08-03 10:13:41.470 Detail, Node004, Queuing (Send) SwitchBinaryCmd_Get (Node=4): 0x01, 0x09, 0x00, 0x13, 0x04, 0x02, 0x25, 0x02, 0x25, 0x27, 0xc6
2019-08-03T10:13:41.470Z z2m:Zwave Success zwave api call setValue
2019-08-03 10:13:43.695 Detail, Node004,   Received: 0x01, 0x18, 0x00, 0x13, 0x25, 0x01, 0x01, 0x9b, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x01, 0x01, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x04, 0x00, 0x00, 0x34
2019-08-03 10:13:43.696 Detail, Node004,   ZW_SEND_DATA Request with callback ID 0x25 received (expected 0x25)
2019-08-03 10:13:43.696 Detail, Node004, Extended TxStatus: Time: 4110, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 1, TxChannel: 1, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 4, FailedLinkFrom: 0, FailedLinkTo: 0
2019-08-03 10:13:43.696 Info, Node004, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2019-08-03 10:13:43.696 Warning, Node004, WARNING: Device is not a sleeping node.
2019-08-03 10:13:43.696 Error, Node004, ERROR: node presumed dead
2019-08-03 10:13:43.696 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2019-08-03 10:13:43.696 Warning, CheckCompletedNodeQueries all=1, deadFound=1 sleepingOnly=1
2019-08-03 10:13:43.696 Info,          Node query processing complete except for dead nodes.
2019-08-03 10:13:43.696 Info, Saving Cache
2019-08-03 10:13:43.696 Info, Node001, Cache Save for Node 1 as its QueryStage_CacheLoad
2019-08-03 10:13:43.697 Info, Node002, Cache Save for Node 2 as its QueryStage_CacheLoad
2019-08-03 10:13:43.698 Info, Node004, Cache Save for Node 4 as its QueryStage_CacheLoad
2019-08-03 10:13:43.705 Detail, Node004,   Expected callbackId was received
2019-08-03 10:13:43.705 Detail, contrlr, Notification: AllNodesQueriedSomeDead
2019-08-03 10:13:43.705 Detail, Node004, Notification: Notification - Node Dead
2019-08-03T10:13:43.706Z z2m:Zwave Network scan complete. Found: 3 nodes
2019-08-03T10:13:43.712Z z2m:Zwave Notification from node 4: Notification - Node Dead (5)
2019-08-03 10:13:49.578 Error, Node004, ERROR: Dropping command because node is presumed dead
2019-08-03 10:13:49.578 Detail, Node004, Removing current message
2019-08-03 10:13:49.578 Detail, Node004, Notification: Notification - TimeOut
2019-08-03 10:13:49.578 Error, Node004, ERROR: Dropping command because node is presumed dead
2019-08-03 10:13:49.578 Detail, Node004, Removing current message
2019-08-03 10:13:49.578 Error, Node004, ERROR: Dropping command because node is presumed dead
2019-08-03 10:13:49.578 Detail, Node004, Removing current message
2019-08-03 10:13:49.578 Detail, Node004, Query Stage Complete (CacheLoad)
2019-08-03 10:13:49.578 Detail, Node004, AdvanceQueries queryPending=0 queryRetries=1 queryStage=CacheLoad live=0
2019-08-03T10:13:49.578Z z2m:Zwave Notification from node 4: Notification - TimeOut (1)
[ Aug  3 10:13:53 Stopping because service disabled. ]
[ Aug  3 10:13:53 Executing stop method (:kill). ]

I'm starting to wonder if my USB stick is bad, I'm using a ZMEEUZB, but most people seem to be using the Aeotec one. Although chip wise they appear the same (as everything is based of the same chip)

sjorge commented 5 years ago

I ordered one of the popular Aeotec Controller Sticks, it should get here next week. Let’s see if that helps, If not, atleast it will have a backup battery

sjorge commented 5 years ago

Since the new stick is on the way and I need to rebuild the network anyway, I thought I'd do that with the old stick as wel.

hard reset the controoler
remove all of zwave2mqtt + libopenzwave
install libopenzwave 1.6
install zwave2mqtt from master
include the multisensor
include the switch
check behavior ==> same as before
re-add the verifychange thingy ==> fails as now I am required to set a topic

^- is this something that just landed in master? Or was this always there and that may be the reason it stopped working because the old saved option was missing a topic?

Also what do I fill in there?

sjorge commented 5 years ago

I swapped to the Aeotec ZW090 as controller, re-added the switch and have the same problem. I did notice it not being immediately detected at startup.

So the function might not be firing to configure the property.

I also ordered a 2nd of the same switch and one from fibaro to play with. The 2nd Aeotec switch has the same problem.

The fibaro one does not, but that is because it works fine without verifychange.

OpenZWave / Zwave2Mqtt

[bug] "Verify Changes" seems broken #58