Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Light always recognized as ON #7937

Closed
nitrag opened this issue Jun 7, 2017 · 5 comments
Closed

Light always recognized as ON #7937

nitrag opened this issue Jun 7, 2017 · 5 comments

Comments

@nitrag
Copy link

nitrag commented Jun 7, 2017

Home Assistant release (hass --version):
0.45.1

Python release (python3 --version):
Python 3.5.2

Component/platform:
Enbrighten Z-Wave Plus Light Bulb (4.5/5 stars, 1000+ reviews)

Description of problem:
I can really only adjust the Level (slider) to turn it on and use the switch to turn it off. Seconds later (the light is off) and HA shows it is ON.

AverageRequestRTT: 141
AverageResponseRTT: 218
Capabilities: Beaming,Routing,Listening
Friendly_name: PorchLight
Is_awake: True
Is_failed: False
Is_info_received: True
Is_ready: True
Is_zwave_plus: True
LastRequestRTT: 160
LastResponseRTT: 238
Manufacturer_name: GE
Max_baud_rate: 40000
Neighbors: 6,7
Node_id: 8
Product_name: Unknown: Type=4c42, Id=3031
Query_stage: Complete
ReceivedCnt: 4
ReceivedDups: 0
ReceivedTS: 2017-06-02 20:58:49:052
ReceivedUnsolicited: 0
Retries: 0
SentCnt: 38
SentFailed: 0
SentTS: 2017-06-06 08:53:02:204

Expected:
Use switch to turn it on/off.

Problem-relevant configuration.yaml entries and steps to reproduce:
It was auto-recognized in HA, I don't have anything in configuration for it.

Z-Wave log during use:

2017-06-06 21:29:41.621 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-06-06 21:29:41.622 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-06-06 21:29:41.622 Warning, Exception: Manager.cpp:2499 - 102 - ValueID passed to GetValueFloatPrecision is not a Decimal Value
2017-06-06 21:38:52.529 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-06 21:38:52.529 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-06 21:38:52.529 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x58, 0xb7
2017-06-06 21:38:52.529 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x59, 0xb7
2017-06-06 21:38:52.529 Detail, 
2017-06-06 21:38:52.529 Info, Node008, Sending (Send) message (Callback ID=0x58, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x58, 0xb7
2017-06-06 21:38:53.529 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:38:53.529 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5a, 0xb4
2017-06-06 21:38:53.529 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:38:53.529 Detail, Node008, Removing current message
2017-06-06 21:38:53.529 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:38:53.530 Detail, 
2017-06-06 21:38:53.530 Info, Node008, Sending (Send) message (Callback ID=0x59, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x59, 0xb7
2017-06-06 21:38:54.529 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:38:54.529 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5b, 0xb5
2017-06-06 21:38:54.530 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:38:54.530 Detail, Node008, Removing current message
2017-06-06 21:38:54.530 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:38:54.530 Detail, 
2017-06-06 21:38:54.530 Info, Node008, Sending (Send) message (Callback ID=0x5a, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5a, 0xb4
2017-06-06 21:38:55.530 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:38:55.530 Detail, Node008, Removing current message
2017-06-06 21:38:55.530 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:38:55.531 Detail, 
2017-06-06 21:38:55.531 Info, Node008, Sending (Send) message (Callback ID=0x5b, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5b, 0xb5
2017-06-06 21:38:56.531 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:38:56.531 Detail, Node008, Removing current message
2017-06-06 21:38:56.531 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:38:57.167 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-06 21:38:57.167 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-06 21:38:57.167 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x5c, 0xb3
2017-06-06 21:38:57.167 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5d, 0xb3
2017-06-06 21:38:57.167 Detail, 
2017-06-06 21:38:57.167 Info, Node008, Sending (Send) message (Callback ID=0x5c, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x5c, 0xb3
2017-06-06 21:38:58.167 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:38:58.167 Detail, Node008, Removing current message
2017-06-06 21:38:58.167 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:38:58.167 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5e, 0xb0
2017-06-06 21:38:58.168 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:38:58.168 Detail, 
2017-06-06 21:38:58.168 Info, Node008, Sending (Send) message (Callback ID=0x5d, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5d, 0xb3
2017-06-06 21:38:59.168 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:38:59.168 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5f, 0xb1
2017-06-06 21:38:59.168 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:38:59.168 Detail, Node008, Removing current message
2017-06-06 21:38:59.168 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:38:59.169 Detail, 
2017-06-06 21:38:59.169 Info, Node008, Sending (Send) message (Callback ID=0x5e, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5e, 0xb0
2017-06-06 21:39:00.169 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:00.169 Detail, Node008, Removing current message
2017-06-06 21:39:00.169 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:00.169 Detail, 
2017-06-06 21:39:00.169 Info, Node008, Sending (Send) message (Callback ID=0x5f, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5f, 0xb1
2017-06-06 21:39:01.169 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:01.169 Detail, Node008, Removing current message
2017-06-06 21:39:01.169 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:02.704 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - �
2017-06-06 21:39:02.704 Info, Node008, SwitchMultilevel::Set - Setting to level 16
2017-06-06 21:39:02.704 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x10, 0x25, 0x60, 0x9f
2017-06-06 21:39:02.704 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x61, 0x8f
2017-06-06 21:39:02.704 Detail, 
2017-06-06 21:39:02.704 Info, Node008, Sending (Send) message (Callback ID=0x60, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x10, 0x25, 0x60, 0x9f
2017-06-06 21:39:03.705 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:03.705 Detail, Node008, Removing current message
2017-06-06 21:39:03.705 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:03.705 Detail, 
2017-06-06 21:39:03.705 Info, Node008, Sending (Send) message (Callback ID=0x61, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x61, 0x8f
2017-06-06 21:39:04.705 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:04.705 Detail, Node008, Removing current message
2017-06-06 21:39:04.705 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:07.096 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-06 21:39:07.096 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-06 21:39:07.096 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x62, 0x8d
2017-06-06 21:39:07.096 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x63, 0x8d
2017-06-06 21:39:07.096 Detail, 
2017-06-06 21:39:07.096 Info, Node008, Sending (Send) message (Callback ID=0x62, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x62, 0x8d
2017-06-06 21:39:08.096 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:08.096 Detail, Node008, Removing current message
2017-06-06 21:39:08.096 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:08.097 Detail, 
2017-06-06 21:39:08.097 Info, Node008, Sending (Send) message (Callback ID=0x63, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x63, 0x8d
2017-06-06 21:39:08.097 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:39:08.097 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x64, 0x8a
2017-06-06 21:39:09.097 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:09.097 Detail, Node008, Removing current message
2017-06-06 21:39:09.097 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:09.097 Detail, 
2017-06-06 21:39:09.097 Info, Node008, Sending (Send) message (Callback ID=0x64, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x64, 0x8a
2017-06-06 21:39:09.098 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:39:09.098 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x65, 0x8b
2017-06-06 21:39:10.098 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:10.098 Detail, Node008, Removing current message
2017-06-06 21:39:10.098 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:10.098 Detail, 
2017-06-06 21:39:10.098 Info, Node008, Sending (Send) message (Callback ID=0x65, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x65, 0x8b
2017-06-06 21:39:10.187 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 8
2017-06-06 21:39:10.187 Info, Node008, SwitchMultilevel::Set - Setting to level 56
2017-06-06 21:39:10.187 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x38, 0x25, 0x66, 0xb1
2017-06-06 21:39:10.187 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x67, 0x89
2017-06-06 21:39:11.098 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:11.098 Detail, Node008, Removing current message
2017-06-06 21:39:11.098 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:11.099 Detail, 
2017-06-06 21:39:11.099 Info, Node008, Sending (Send) message (Callback ID=0x66, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x38, 0x25, 0x66, 0xb1
2017-06-06 21:39:12.099 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:12.099 Detail, Node008, Removing current message
2017-06-06 21:39:12.099 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:12.099 Detail, 
2017-06-06 21:39:12.099 Info, Node008, Sending (Send) message (Callback ID=0x67, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x67, 0x89
2017-06-06 21:39:13.099 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:13.099 Detail, Node008, Removing current message
2017-06-06 21:39:13.100 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:14.328 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-06 21:39:14.328 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-06 21:39:14.328 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x68, 0x87
2017-06-06 21:39:14.328 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x69, 0x87
2017-06-06 21:39:14.328 Detail, 
2017-06-06 21:39:14.328 Info, Node008, Sending (Send) message (Callback ID=0x68, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x68, 0x87
2017-06-06 21:39:15.329 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:15.329 Detail, Node008, Removing current message
2017-06-06 21:39:15.329 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:15.329 Detail, 
2017-06-06 21:39:15.329 Info, Node008, Sending (Send) message (Callback ID=0x69, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x69, 0x87
2017-06-06 21:39:15.330 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:39:15.330 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6a, 0x84
2017-06-06 21:39:16.329 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:16.329 Detail, Node008, Removing current message
2017-06-06 21:39:16.329 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:16.330 Detail, 
2017-06-06 21:39:16.330 Info, Node008, Sending (Send) message (Callback ID=0x6a, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6a, 0x84
2017-06-06 21:39:16.331 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:39:16.331 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6b, 0x85
2017-06-06 21:39:17.330 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:17.330 Detail, Node008, Removing current message
2017-06-06 21:39:17.330 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:17.330 Detail, 
2017-06-06 21:39:17.330 Info, Node008, Sending (Send) message (Callback ID=0x6b, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6b, 0x85
2017-06-06 21:39:18.331 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:18.331 Detail, Node008, Removing current message
2017-06-06 21:39:18.331 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:19.064 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-06 21:39:19.064 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-06 21:39:19.064 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x6c, 0x83
2017-06-06 21:39:19.064 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6d, 0x83
2017-06-06 21:39:19.064 Detail, 
2017-06-06 21:39:19.064 Info, Node008, Sending (Send) message (Callback ID=0x6c, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x6c, 0x83
2017-06-06 21:39:20.064 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:20.064 Detail, Node008, Removing current message
2017-06-06 21:39:20.064 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:20.065 Detail, 
2017-06-06 21:39:20.065 Info, Node008, Sending (Send) message (Callback ID=0x6d, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6d, 0x83
2017-06-06 21:39:20.065 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:39:20.065 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6e, 0x80
2017-06-06 21:39:21.065 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:21.065 Detail, Node008, Removing current message
2017-06-06 21:39:21.065 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:21.065 Detail, 
2017-06-06 21:39:21.065 Info, Node008, Sending (Send) message (Callback ID=0x6e, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6e, 0x80
2017-06-06 21:39:21.066 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:39:21.066 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6f, 0x81
2017-06-06 21:39:22.065 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:22.065 Detail, Node008, Removing current message
2017-06-06 21:39:22.065 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:22.066 Detail, 
2017-06-06 21:39:22.066 Info, Node008, Sending (Send) message (Callback ID=0x6f, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x6f, 0x81
2017-06-06 21:39:23.066 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:23.066 Detail, Node008, Removing current message
2017-06-06 21:39:23.066 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:23.975 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - c
2017-06-06 21:39:23.975 Info, Node008, SwitchMultilevel::Set - Setting to level 99
2017-06-06 21:39:23.975 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x63, 0x25, 0x70, 0xfc
2017-06-06 21:39:23.975 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x71, 0x9f
2017-06-06 21:39:23.975 Detail, 
2017-06-06 21:39:23.975 Info, Node008, Sending (Send) message (Callback ID=0x70, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x63, 0x25, 0x70, 0xfc
2017-06-06 21:39:24.975 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:24.975 Detail, Node008, Removing current message
2017-06-06 21:39:24.975 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:24.975 Detail, 
2017-06-06 21:39:24.975 Info, Node008, Sending (Send) message (Callback ID=0x71, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x71, 0x9f
2017-06-06 21:39:25.976 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:25.976 Detail, Node008, Removing current message
2017-06-06 21:39:25.976 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:28.798 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-06 21:39:28.798 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-06 21:39:28.798 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x72, 0x9d
2017-06-06 21:39:28.798 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x73, 0x9d
2017-06-06 21:39:28.798 Detail, 
2017-06-06 21:39:28.798 Info, Node008, Sending (Send) message (Callback ID=0x72, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x72, 0x9d
2017-06-06 21:39:29.799 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:39:29.799 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x74, 0x9a
2017-06-06 21:39:29.799 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:29.799 Detail, Node008, Removing current message
2017-06-06 21:39:29.799 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:29.799 Detail, 
2017-06-06 21:39:29.799 Info, Node008, Sending (Send) message (Callback ID=0x73, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x73, 0x9d
2017-06-06 21:39:30.799 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-06 21:39:30.799 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x75, 0x9b
2017-06-06 21:39:30.799 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:30.799 Detail, Node008, Removing current message
2017-06-06 21:39:30.799 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:30.800 Detail, 
2017-06-06 21:39:30.800 Info, Node008, Sending (Send) message (Callback ID=0x74, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x74, 0x9a
2017-06-06 21:39:31.800 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:31.800 Detail, Node008, Removing current message
2017-06-06 21:39:31.800 Detail, Node008, Notification: Notification - TimeOut
2017-06-06 21:39:31.800 Detail, 
2017-06-06 21:39:31.800 Info, Node008, Sending (Send) message (Callback ID=0x75, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x75, 0x9b
2017-06-06 21:39:32.800 Error, Node008, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-06-06 21:39:32.800 Detail, Node008, Removing current message
2017-06-06 21:39:32.800 Detail, Node008, Notification: Notification - TimeOut
@turbokongen
Copy link
Contributor

You probably need to set value_refresh in device_config section of zwave in the configuration.yaml
https://home-assistant.io/docs/z-wave/#configuration

@turbokongen
Copy link
Contributor

The timeouts can suggest problem for ozw to communicate with the bulb too.

@nitrag
Copy link
Author

nitrag commented Jun 22, 2017

So I've install HA on a Pi3 10 feet from light. No longer getting errors but light is not displaying OFF. I think the problem is that it thinks the brightness is > 0 so it thinks it's still ON.

Below is me toggling ON/OFF.

2017-06-21 23:34:06 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1977918992-11>
2017-06-21 23:34:06 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=127, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=53, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=128, query_stage=Complete, receivedTS=2017-06-21 23:33:20:394 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=20, sentCnt=29, is_awake=True, neighbors={6, 7}, averageRequestRTT=53, sentTS=2017-06-21 23:33:20:266  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=146, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=73, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=137, query_stage=Complete, receivedTS=2017-06-21 23:34:06:754 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=21, sentCnt=31, is_awake=True, neighbors={6, 7}, averageRequestRTT=62, sentTS=2017-06-21 23:34:06:608  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:34:06 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.level_8_0, old_state=<state light.level_8_0=on; brightness=85.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>, new_state=<state light.level_8_0=on; brightness=255.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>>
2017-06-21 23:34:11 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_off, domain=homeassistant, service_call_id=1977918992-12, service_data=entity_id=light.level_8_0>
2017-06-21 23:34:11 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_off, domain=light, service_call_id=1977918992-13, service_data=entity_id=['light.level_8_0']>
2017-06-21 23:34:11 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1977918992-13>
2017-06-21 23:34:11 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1977918992-12>
2017-06-21 23:34:12 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=146, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=73, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=137, query_stage=Complete, receivedTS=2017-06-21 23:34:06:754 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=21, sentCnt=31, is_awake=True, neighbors={6, 7}, averageRequestRTT=62, sentTS=2017-06-21 23:34:06:608  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=144, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=71, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=140, query_stage=Complete, receivedTS=2017-06-21 23:34:11:905 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=22, sentCnt=33, is_awake=True, neighbors={6, 7}, averageRequestRTT=63, sentTS=2017-06-21 23:34:11:760  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:34:12 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.level_8_0, old_state=<state light.level_8_0=on; brightness=255.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>, new_state=<state light.level_8_0=on; brightness=252.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>>
2017-06-21 23:34:13 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=144, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=71, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=140, query_stage=Complete, receivedTS=2017-06-21 23:34:11:905 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=22, sentCnt=33, is_awake=True, neighbors={6, 7}, averageRequestRTT=63, sentTS=2017-06-21 23:34:11:760  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=343, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=49, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=241, query_stage=Complete, receivedTS=2017-06-21 23:34:13:053 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=23, sentCnt=34, is_awake=True, neighbors={6, 7}, averageRequestRTT=56, sentTS=2017-06-21 23:34:12:710  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:34:13 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.level_8_0, old_state=<state light.level_8_0=on; brightness=252.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>, new_state=<state light.level_8_0=on; brightness=170.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>>
2017-06-21 23:34:13 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=343, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=49, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=241, query_stage=Complete, receivedTS=2017-06-21 23:34:13:053 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=23, sentCnt=34, is_awake=True, neighbors={6, 7}, averageRequestRTT=56, sentTS=2017-06-21 23:34:12:710  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=123, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=62, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=182, query_stage=Complete, receivedTS=2017-06-21 23:34:13:834 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=24, sentCnt=35, is_awake=True, neighbors={6, 7}, averageRequestRTT=59, sentTS=2017-06-21 23:34:13:711  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:34:13 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.level_8_0, old_state=<state light.level_8_0=on; brightness=170.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>, new_state=<state light.level_8_0=on; brightness=85.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>>
2017-06-21 23:34:30 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sun.sun, old_state=<state sun.sun=below_horizon; next_dawn=2017-06-22T09:44:13+00:00, next_setting=2017-06-23T00:31:40+00:00, next_rising=2017-06-22T10:12:52+00:00, azimuth=331.61, next_midnight=2017-06-22T05:22:25+00:00, friendly_name=Sun, elevation=-28.05, next_dusk=2017-06-23T01:00:19+00:00, next_noon=2017-06-22T17:22:16+00:00 @ 2017-06-21T19:57:17.375461-05:00>, new_state=<state sun.sun=below_horizon; next_dawn=2017-06-22T09:44:13+00:00, next_setting=2017-06-23T00:31:40+00:00, next_rising=2017-06-22T10:12:52+00:00, azimuth=331.85, next_midnight=2017-06-22T05:22:25+00:00, friendly_name=Sun, elevation=-28.15, next_dusk=2017-06-23T01:00:19+00:00, next_noon=2017-06-22T17:22:16+00:00 @ 2017-06-21T19:57:17.375461-05:00>>
2017-06-21 23:34:33 INFO (Thread-1) [homeassistant.components.device_tracker.mikrotik] Loading wireless devices from Mikrotik (192.168.156.1) ...
2017-06-21 23:34:42 INFO (MainThread) [homeassistant.components.http] Serving /states to 192.168.156.247 (auth: False)
2017-06-21 23:34:42 INFO (MainThread) [homeassistant.components.http] Serving /api/websocket to 192.168.156.247 (auth: False)
2017-06-21 23:34:46 INFO (MainThread) [homeassistant.components.http] Serving /api/history/period to 192.168.156.247 (auth: True)
2017-06-21 23:34:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_on, domain=light, service_call_id=1977918992-14, service_data=entity_id=light.level_8_0, brightness=255>
2017-06-21 23:34:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1977918992-14>
2017-06-21 23:34:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=123, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=62, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=182, query_stage=Complete, receivedTS=2017-06-21 23:34:13:834 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=24, sentCnt=35, is_awake=True, neighbors={6, 7}, averageRequestRTT=59, sentTS=2017-06-21 23:34:13:711  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=140, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=67, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=161, query_stage=Complete, receivedTS=2017-06-21 23:34:53:385 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=25, sentCnt=37, is_awake=True, neighbors={6, 7}, averageRequestRTT=60, sentTS=2017-06-21 23:34:53:244  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:34:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.level_8_0, old_state=<state light.level_8_0=on; brightness=85.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>, new_state=<state light.level_8_0=on; brightness=255.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>>
2017-06-21 23:34:56 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_off, domain=light, service_call_id=1977918992-15, service_data=entity_id=light.level_8_0>
2017-06-21 23:34:56 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1977918992-15>
2017-06-21 23:34:57 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=140, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=67, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=161, query_stage=Complete, receivedTS=2017-06-21 23:34:53:385 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=25, sentCnt=37, is_awake=True, neighbors={6, 7}, averageRequestRTT=60, sentTS=2017-06-21 23:34:53:244  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=147, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=71, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=154, query_stage=Complete, receivedTS=2017-06-21 23:34:57:015 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=26, sentCnt=39, is_awake=True, neighbors={6, 7}, averageRequestRTT=63, sentTS=2017-06-21 23:34:56:869  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:34:57 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.level_8_0, old_state=<state light.level_8_0=on; brightness=255.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>, new_state=<state light.level_8_0=on; brightness=252.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>>
2017-06-21 23:34:58 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=147, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=71, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=154, query_stage=Complete, receivedTS=2017-06-21 23:34:57:015 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=26, sentCnt=39, is_awake=True, neighbors={6, 7}, averageRequestRTT=63, sentTS=2017-06-21 23:34:56:869  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=306, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=50, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=230, query_stage=Complete, receivedTS=2017-06-21 23:34:58:125 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=27, sentCnt=40, is_awake=True, neighbors={6, 7}, averageRequestRTT=56, sentTS=2017-06-21 23:34:57:819  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:34:58 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.level_8_0, old_state=<state light.level_8_0=on; brightness=252.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>, new_state=<state light.level_8_0=on; brightness=170.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>>
2017-06-21 23:34:59 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=306, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=50, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=230, query_stage=Complete, receivedTS=2017-06-21 23:34:58:125 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=27, sentCnt=40, is_awake=True, neighbors={6, 7}, averageRequestRTT=56, sentTS=2017-06-21 23:34:57:819  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=126, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=50, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=178, query_stage=Complete, receivedTS=2017-06-21 23:34:58:946 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=28, sentCnt=41, is_awake=True, neighbors={6, 7}, averageRequestRTT=53, sentTS=2017-06-21 23:34:58:819  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:34:59 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.level_8_0, old_state=<state light.level_8_0=on; brightness=170.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>, new_state=<state light.level_8_0=on; brightness=85.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>>
2017-06-21 23:35:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_off, domain=light, service_call_id=1977918992-16, service_data=entity_id=light.level_8_0>
2017-06-21 23:35:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1977918992-16>
2017-06-21 23:35:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=126, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=50, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=178, query_stage=Complete, receivedTS=2017-06-21 23:34:58:946 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=28, sentCnt=41, is_awake=True, neighbors={6, 7}, averageRequestRTT=53, sentTS=2017-06-21 23:34:58:819  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=141, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=67, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=159, query_stage=Complete, receivedTS=2017-06-21 23:35:01:745 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=29, sentCnt=43, is_awake=True, neighbors={6, 7}, averageRequestRTT=59, sentTS=2017-06-21 23:35:01:604  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:35:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.level_8_0, old_state=<state light.level_8_0=on; brightness=85.0, new_entity_id=light.level, friendly_name=Level, max_mireds=500, min_mireds=154, old_entity_id=light.level_8_0, value_index=0, value_instance=1, node_id=8, supported_features=1 @ 2017-06-21T22:33:13.237679-05:00>, new_state=<state light.level_8_0=off; old_entity_id=light.level_8_0, supported_features=1, new_entity_id=light.level, value_index=0, value_instance=1, node_id=8, friendly_name=Level @ 2017-06-21T22:35:01.859928-05:00>>
2017-06-21 23:35:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=group.all_lights, old_state=<state group.all_lights=on; auto=True, entity_id=('light.level_8_0',), hidden=True, order=1, friendly_name=all lights, assumed_state=False @ 2017-06-21T22:33:13.244751-05:00>, new_state=<state group.all_lights=off; auto=True, entity_id=('light.level_8_0',), hidden=True, order=1, friendly_name=all lights, assumed_state=False @ 2017-06-21T22:35:01.873156-05:00>>
2017-06-21 23:35:02 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=141, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=67, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=159, query_stage=Complete, receivedTS=2017-06-21 23:35:01:745 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=29, sentCnt=43, is_awake=True, neighbors={6, 7}, averageRequestRTT=59, sentTS=2017-06-21 23:35:01:604  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=121, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=49, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=140, query_stage=Complete, receivedTS=2017-06-21 23:35:02:675 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=30, sentCnt=44, is_awake=True, neighbors={6, 7}, averageRequestRTT=54, sentTS=2017-06-21 23:35:02:554  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:35:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=zwave.__8, old_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=121, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=49, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=140, query_stage=Complete, receivedTS=2017-06-21 23:35:02:675 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=30, sentCnt=44, is_awake=True, neighbors={6, 7}, averageRequestRTT=54, sentTS=2017-06-21 23:35:02:554  @ 2017-06-21T20:05:44.720597-05:00>, new_state=<state zwave.__8=Ready; sentFailed=0, lastResponseRTT=121, is_zwave_plus=True, max_baud_rate=40000, new_entity_id=zwave._, retries=0, node_name= , manufacturer_name=, is_failed=False, is_info_received=True, lastRequestRTT=49, capabilities={'listening', 'beaming', 'routing'}, is_ready=True, old_entity_id=zwave.__8, node_id=8, averageResponseRTT=130, query_stage=Complete, receivedTS=2017-06-21 23:35:03:676 , product_name=, friendly_name= , receivedUnsolicited=1, receivedDups=1, receivedCnt=31, sentCnt=45, is_awake=True, neighbors={6, 7}, averageRequestRTT=51, sentTS=2017-06-21 23:35:03:554  @ 2017-06-21T20:05:44.720597-05:00>>
2017-06-21 23:35:07 INFO (MainThread) [homeassistant.components.http] Serving /states to 192.168.156.247 (auth: False)
2017-06-21 23:35:07 INFO (MainThread) [homeassistant.components.http] Serving /api/websocket to 192.168.156.247 (auth: False)
2017-06-21 23:34:06.556 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - c
2017-06-21 23:34:06.556 Info, Node008, SwitchMultilevel::Set - Setting to level 99
2017-06-21 23:34:06.557 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x63, 0x25, 0x4f, 0xc3
2017-06-21 23:34:06.557 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x50, 0xbe
2017-06-21 23:34:06.557 Detail, 
2017-06-21 23:34:06.557 Info, Node008, Sending (Send) message (Callback ID=0x4f, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x63, 0x25, 0x4f, 0xc3
2017-06-21 23:34:06.565 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:06.566 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:06.607 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x4f, 0x00, 0x00, 0x05, 0xa1
2017-06-21 23:34:06.607 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x4f received (expected 0x4f)
2017-06-21 23:34:06.607 Info, Node008, Request RTT 50 Average Request RTT 51
2017-06-21 23:34:06.608 Detail,   Expected callbackId was received
2017-06-21 23:34:06.608 Detail,   Expected reply was received
2017-06-21 23:34:06.608 Detail,   Message transaction complete
2017-06-21 23:34:06.608 Detail, 
2017-06-21 23:34:06.608 Detail, Node008, Removing current message
2017-06-21 23:34:06.608 Detail, 
2017-06-21 23:34:06.608 Info, Node008, Sending (Send) message (Callback ID=0x50, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x50, 0xbe
2017-06-21 23:34:06.617 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:06.618 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:06.681 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x50, 0x00, 0x00, 0x07, 0xbc
2017-06-21 23:34:06.681 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x50 received (expected 0x50)
2017-06-21 23:34:06.681 Info, Node008, Request RTT 73 Average Request RTT 62
2017-06-21 23:34:06.682 Detail,   Expected callbackId was received
2017-06-21 23:34:06.754 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x63, 0xbf
2017-06-21 23:34:06.754 Detail, 
2017-06-21 23:34:06.754 Info, Node008, Response RTT 146 Average Response RTT 137
2017-06-21 23:34:06.754 Info, Node008, Received SwitchMultiLevel report: level=99
2017-06-21 23:34:06.755 Detail, Node008, Refreshed Value: old value=33, new value=99, type=byte
2017-06-21 23:34:06.755 Detail, Node008, Changes to this value are not verified
2017-06-21 23:34:06.755 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:34:06.755 Detail, Node008,   Message transaction complete
2017-06-21 23:34:06.755 Detail, 
2017-06-21 23:34:06.755 Detail, Node008, Removing current message
2017-06-21 23:34:06.755 Detail, Node008, Notification: ValueChanged
2017-06-21 23:34:11.707 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-21 23:34:11.708 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-21 23:34:11.708 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x51, 0xbe
2017-06-21 23:34:11.708 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x52, 0xbc
2017-06-21 23:34:11.708 Detail, 
2017-06-21 23:34:11.708 Info, Node008, Sending (Send) message (Callback ID=0x51, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x51, 0xbe
2017-06-21 23:34:11.717 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:11.718 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:11.758 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x51, 0x00, 0x00, 0x05, 0xbf
2017-06-21 23:34:11.759 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x51 received (expected 0x51)
2017-06-21 23:34:11.759 Info, Node008, Request RTT 50 Average Request RTT 56
2017-06-21 23:34:11.759 Detail,   Expected callbackId was received
2017-06-21 23:34:11.759 Detail,   Expected reply was received
2017-06-21 23:34:11.759 Detail,   Message transaction complete
2017-06-21 23:34:11.759 Detail, 
2017-06-21 23:34:11.759 Detail, Node008, Removing current message
2017-06-21 23:34:11.759 Detail, 
2017-06-21 23:34:11.760 Info, Node008, Sending (Send) message (Callback ID=0x52, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x52, 0xbc
2017-06-21 23:34:11.769 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:11.769 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:11.831 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x52, 0x00, 0x00, 0x07, 0xbe
2017-06-21 23:34:11.831 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x52 received (expected 0x52)
2017-06-21 23:34:11.832 Info, Node008, Request RTT 71 Average Request RTT 63
2017-06-21 23:34:11.832 Detail,   Expected callbackId was received
2017-06-21 23:34:11.904 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x62, 0xbe
2017-06-21 23:34:11.904 Detail, 
2017-06-21 23:34:11.905 Info, Node008, Response RTT 144 Average Response RTT 140
2017-06-21 23:34:11.905 Info, Node008, Received SwitchMultiLevel report: level=98
2017-06-21 23:34:11.905 Detail, Node008, Refreshed Value: old value=99, new value=98, type=byte
2017-06-21 23:34:11.905 Detail, Node008, Changes to this value are not verified
2017-06-21 23:34:11.905 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:34:11.905 Detail, Node008,   Message transaction complete
2017-06-21 23:34:11.905 Detail, 
2017-06-21 23:34:11.905 Detail, Node008, Removing current message
2017-06-21 23:34:11.905 Detail, Node008, Notification: ValueChanged
2017-06-21 23:34:12.709 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-21 23:34:12.709 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x53, 0xbd
2017-06-21 23:34:12.710 Detail, 
2017-06-21 23:34:12.710 Info, Node008, Sending (Send) message (Callback ID=0x53, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x53, 0xbd
2017-06-21 23:34:12.718 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:12.718 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:12.760 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x53, 0x00, 0x00, 0x05, 0xbd
2017-06-21 23:34:12.760 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x53 received (expected 0x53)
2017-06-21 23:34:12.760 Info, Node008, Request RTT 49 Average Request RTT 56
2017-06-21 23:34:12.760 Detail,   Expected callbackId was received
2017-06-21 23:34:12.834 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x42, 0x9e
2017-06-21 23:34:13.053 Detail, 
2017-06-21 23:34:13.053 Info, Node008, Response RTT 343 Average Response RTT 241
2017-06-21 23:34:13.053 Info, Node008, Received SwitchMultiLevel report: level=66
2017-06-21 23:34:13.053 Detail, Node008, Refreshed Value: old value=98, new value=66, type=byte
2017-06-21 23:34:13.053 Detail, Node008, Changes to this value are not verified
2017-06-21 23:34:13.053 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:34:13.053 Detail, Node008,   Message transaction complete
2017-06-21 23:34:13.053 Detail, 
2017-06-21 23:34:13.053 Detail, Node008, Removing current message
2017-06-21 23:34:13.053 Detail, Node008, Notification: ValueChanged
2017-06-21 23:34:13.710 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-21 23:34:13.710 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x54, 0xba
2017-06-21 23:34:13.710 Detail, 
2017-06-21 23:34:13.710 Info, Node008, Sending (Send) message (Callback ID=0x54, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x54, 0xba
2017-06-21 23:34:13.718 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:13.718 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:13.760 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x54, 0x00, 0x00, 0x05, 0xba
2017-06-21 23:34:13.773 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x54 received (expected 0x54)
2017-06-21 23:34:13.773 Info, Node008, Request RTT 62 Average Request RTT 59
2017-06-21 23:34:13.773 Detail,   Expected callbackId was received
2017-06-21 23:34:13.834 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x21, 0xfd
2017-06-21 23:34:13.834 Detail, 
2017-06-21 23:34:13.834 Info, Node008, Response RTT 123 Average Response RTT 182
2017-06-21 23:34:13.834 Info, Node008, Received SwitchMultiLevel report: level=33
2017-06-21 23:34:13.835 Detail, Node008, Refreshed Value: old value=66, new value=33, type=byte
2017-06-21 23:34:13.835 Detail, Node008, Changes to this value are not verified
2017-06-21 23:34:13.835 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:34:13.835 Detail, Node008,   Message transaction complete
2017-06-21 23:34:13.835 Detail, 
2017-06-21 23:34:13.835 Detail, Node008, Removing current message
2017-06-21 23:34:13.835 Detail, Node008, Notification: ValueChanged
2017-06-21 23:34:53.191 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - c
2017-06-21 23:34:53.192 Info, Node008, SwitchMultilevel::Set - Setting to level 99
2017-06-21 23:34:53.192 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x63, 0x25, 0x55, 0xd9
2017-06-21 23:34:53.192 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x56, 0xb8
2017-06-21 23:34:53.192 Detail, 
2017-06-21 23:34:53.193 Info, Node008, Sending (Send) message (Callback ID=0x55, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x63, 0x25, 0x55, 0xd9
2017-06-21 23:34:53.201 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:53.201 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:53.243 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x55, 0x00, 0x00, 0x04, 0xba
2017-06-21 23:34:53.244 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x55 received (expected 0x55)
2017-06-21 23:34:53.244 Info, Node008, Request RTT 50 Average Request RTT 54
2017-06-21 23:34:53.244 Detail,   Expected callbackId was received
2017-06-21 23:34:53.244 Detail,   Expected reply was received
2017-06-21 23:34:53.244 Detail,   Message transaction complete
2017-06-21 23:34:53.244 Detail, 
2017-06-21 23:34:53.244 Detail, Node008, Removing current message
2017-06-21 23:34:53.244 Detail, 
2017-06-21 23:34:53.244 Info, Node008, Sending (Send) message (Callback ID=0x56, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x56, 0xb8
2017-06-21 23:34:53.253 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:53.254 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:53.311 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x56, 0x00, 0x00, 0x06, 0xbb
2017-06-21 23:34:53.311 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x56 received (expected 0x56)
2017-06-21 23:34:53.312 Info, Node008, Request RTT 67 Average Request RTT 60
2017-06-21 23:34:53.312 Detail,   Expected callbackId was received
2017-06-21 23:34:53.385 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x63, 0xbf
2017-06-21 23:34:53.385 Detail, 
2017-06-21 23:34:53.385 Info, Node008, Response RTT 140 Average Response RTT 161
2017-06-21 23:34:53.385 Info, Node008, Received SwitchMultiLevel report: level=99
2017-06-21 23:34:53.386 Detail, Node008, Refreshed Value: old value=33, new value=99, type=byte
2017-06-21 23:34:53.386 Detail, Node008, Changes to this value are not verified
2017-06-21 23:34:53.386 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:34:53.386 Detail, Node008,   Message transaction complete
2017-06-21 23:34:53.386 Detail, 
2017-06-21 23:34:53.386 Detail, Node008, Removing current message
2017-06-21 23:34:53.386 Detail, Node008, Notification: ValueChanged
2017-06-21 23:34:56.815 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-21 23:34:56.816 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-21 23:34:56.816 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x57, 0xb8
2017-06-21 23:34:56.816 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x58, 0xb6
2017-06-21 23:34:56.817 Detail, 
2017-06-21 23:34:56.817 Info, Node008, Sending (Send) message (Callback ID=0x57, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x57, 0xb8
2017-06-21 23:34:56.825 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:56.826 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:56.868 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x57, 0x00, 0x00, 0x05, 0xb9
2017-06-21 23:34:56.868 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x57 received (expected 0x57)
2017-06-21 23:34:56.868 Info, Node008, Request RTT 51 Average Request RTT 55
2017-06-21 23:34:56.869 Detail,   Expected callbackId was received
2017-06-21 23:34:56.869 Detail,   Expected reply was received
2017-06-21 23:34:56.869 Detail,   Message transaction complete
2017-06-21 23:34:56.869 Detail, 
2017-06-21 23:34:56.869 Detail, Node008, Removing current message
2017-06-21 23:34:56.869 Detail, 
2017-06-21 23:34:56.869 Info, Node008, Sending (Send) message (Callback ID=0x58, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x58, 0xb6
2017-06-21 23:34:56.878 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:56.878 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:56.941 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x58, 0x00, 0x00, 0x07, 0xb4
2017-06-21 23:34:56.941 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x58 received (expected 0x58)
2017-06-21 23:34:56.941 Info, Node008, Request RTT 71 Average Request RTT 63
2017-06-21 23:34:56.941 Detail,   Expected callbackId was received
2017-06-21 23:34:57.015 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x62, 0xbe
2017-06-21 23:34:57.015 Detail, 
2017-06-21 23:34:57.015 Info, Node008, Response RTT 147 Average Response RTT 154
2017-06-21 23:34:57.016 Info, Node008, Received SwitchMultiLevel report: level=98
2017-06-21 23:34:57.016 Detail, Node008, Refreshed Value: old value=99, new value=98, type=byte
2017-06-21 23:34:57.016 Detail, Node008, Changes to this value are not verified
2017-06-21 23:34:57.016 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:34:57.016 Detail, Node008,   Message transaction complete
2017-06-21 23:34:57.016 Detail, 
2017-06-21 23:34:57.016 Detail, Node008, Removing current message
2017-06-21 23:34:57.016 Detail, Node008, Notification: ValueChanged
2017-06-21 23:34:57.818 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-21 23:34:57.818 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x59, 0xb7
2017-06-21 23:34:57.819 Detail, 
2017-06-21 23:34:57.819 Info, Node008, Sending (Send) message (Callback ID=0x59, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x59, 0xb7
2017-06-21 23:34:57.828 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:57.828 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:57.869 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x59, 0x00, 0x00, 0x05, 0xb7
2017-06-21 23:34:57.869 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x59 received (expected 0x59)
2017-06-21 23:34:57.869 Info, Node008, Request RTT 50 Average Request RTT 56
2017-06-21 23:34:57.869 Detail,   Expected callbackId was received
2017-06-21 23:34:57.944 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x42, 0x9e
2017-06-21 23:34:58.125 Detail, 
2017-06-21 23:34:58.125 Info, Node008, Response RTT 306 Average Response RTT 230
2017-06-21 23:34:58.125 Info, Node008, Received SwitchMultiLevel report: level=66
2017-06-21 23:34:58.125 Detail, Node008, Refreshed Value: old value=98, new value=66, type=byte
2017-06-21 23:34:58.125 Detail, Node008, Changes to this value are not verified
2017-06-21 23:34:58.125 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:34:58.125 Detail, Node008,   Message transaction complete
2017-06-21 23:34:58.125 Detail, 
2017-06-21 23:34:58.125 Detail, Node008, Removing current message
2017-06-21 23:34:58.126 Detail, Node008, Notification: ValueChanged
2017-06-21 23:34:58.819 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-21 23:34:58.819 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5a, 0xb4
2017-06-21 23:34:58.819 Detail, 
2017-06-21 23:34:58.819 Info, Node008, Sending (Send) message (Callback ID=0x5a, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5a, 0xb4
2017-06-21 23:34:58.827 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:34:58.828 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:34:58.869 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x5a, 0x00, 0x00, 0x05, 0xb4
2017-06-21 23:34:58.869 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x5a received (expected 0x5a)
2017-06-21 23:34:58.869 Info, Node008, Request RTT 50 Average Request RTT 53
2017-06-21 23:34:58.870 Detail,   Expected callbackId was received
2017-06-21 23:34:58.945 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x21, 0xfd
2017-06-21 23:34:58.945 Detail, 
2017-06-21 23:34:58.946 Info, Node008, Response RTT 126 Average Response RTT 178
2017-06-21 23:34:58.946 Info, Node008, Received SwitchMultiLevel report: level=33
2017-06-21 23:34:58.946 Detail, Node008, Refreshed Value: old value=66, new value=33, type=byte
2017-06-21 23:34:58.946 Detail, Node008, Changes to this value are not verified
2017-06-21 23:34:58.946 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:34:58.946 Detail, Node008,   Message transaction complete
2017-06-21 23:34:58.946 Detail, 
2017-06-21 23:34:58.946 Detail, Node008, Removing current message
2017-06-21 23:34:58.946 Detail, Node008, Notification: ValueChanged
2017-06-21 23:35:01.551 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-21 23:35:01.552 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-21 23:35:01.552 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x5b, 0xb4
2017-06-21 23:35:01.552 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5c, 0xb2
2017-06-21 23:35:01.552 Detail, 
2017-06-21 23:35:01.552 Info, Node008, Sending (Send) message (Callback ID=0x5b, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x5b, 0xb4
2017-06-21 23:35:01.560 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:01.561 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:01.603 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x5b, 0x00, 0x00, 0x04, 0xb4
2017-06-21 23:35:01.603 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x5b received (expected 0x5b)
2017-06-21 23:35:01.603 Info, Node008, Request RTT 51 Average Request RTT 52
2017-06-21 23:35:01.604 Detail,   Expected callbackId was received
2017-06-21 23:35:01.604 Detail,   Expected reply was received
2017-06-21 23:35:01.604 Detail,   Message transaction complete
2017-06-21 23:35:01.604 Detail, 
2017-06-21 23:35:01.604 Detail, Node008, Removing current message
2017-06-21 23:35:01.604 Detail, 
2017-06-21 23:35:01.604 Info, Node008, Sending (Send) message (Callback ID=0x5c, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5c, 0xb2
2017-06-21 23:35:01.613 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:01.618 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:01.671 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x5c, 0x00, 0x00, 0x06, 0xb1
2017-06-21 23:35:01.671 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x5c received (expected 0x5c)
2017-06-21 23:35:01.672 Info, Node008, Request RTT 67 Average Request RTT 59
2017-06-21 23:35:01.672 Detail,   Expected callbackId was received
2017-06-21 23:35:01.745 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x00, 0xdc
2017-06-21 23:35:01.745 Detail, 
2017-06-21 23:35:01.745 Info, Node008, Response RTT 141 Average Response RTT 159
2017-06-21 23:35:01.746 Info, Node008, Received SwitchMultiLevel report: level=0
2017-06-21 23:35:01.746 Detail, Node008, Refreshed Value: old value=33, new value=0, type=byte
2017-06-21 23:35:01.746 Detail, Node008, Changes to this value are not verified
2017-06-21 23:35:01.746 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:35:01.746 Detail, Node008,   Message transaction complete
2017-06-21 23:35:01.746 Detail, 
2017-06-21 23:35:01.746 Detail, Node008, Removing current message
2017-06-21 23:35:01.746 Detail, Node008, Notification: ValueChanged
2017-06-21 23:35:02.553 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-21 23:35:02.553 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5d, 0xb3
2017-06-21 23:35:02.553 Detail, 
2017-06-21 23:35:02.553 Info, Node008, Sending (Send) message (Callback ID=0x5d, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5d, 0xb3
2017-06-21 23:35:02.561 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:02.562 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:02.603 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x5d, 0x00, 0x00, 0x04, 0xb2
2017-06-21 23:35:02.603 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x5d received (expected 0x5d)
2017-06-21 23:35:02.603 Info, Node008, Request RTT 49 Average Request RTT 54
2017-06-21 23:35:02.603 Detail,   Expected callbackId was received
2017-06-21 23:35:02.675 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x00, 0xdc
2017-06-21 23:35:02.675 Detail, 
2017-06-21 23:35:02.675 Info, Node008, Response RTT 121 Average Response RTT 140
2017-06-21 23:35:02.675 Info, Node008, Received SwitchMultiLevel report: level=0
2017-06-21 23:35:02.675 Detail, Node008, Refreshed Value: old value=0, new value=0, type=byte
2017-06-21 23:35:02.675 Detail, Node008, Changes to this value are not verified
2017-06-21 23:35:02.675 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:35:02.675 Detail, Node008,   Message transaction complete
2017-06-21 23:35:02.675 Detail, 
2017-06-21 23:35:02.676 Detail, Node008, Removing current message
2017-06-21 23:35:02.676 Detail, Node008, Notification: ValueChanged
2017-06-21 23:35:03.553 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-21 23:35:03.554 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5e, 0xb0
2017-06-21 23:35:03.554 Detail, 
2017-06-21 23:35:03.554 Info, Node008, Sending (Send) message (Callback ID=0x5e, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x5e, 0xb0
2017-06-21 23:35:03.563 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:03.563 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:03.604 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x5e, 0x00, 0x00, 0x04, 0xb1
2017-06-21 23:35:03.604 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x5e received (expected 0x5e)
2017-06-21 23:35:03.604 Info, Node008, Request RTT 49 Average Request RTT 51
2017-06-21 23:35:03.604 Detail,   Expected callbackId was received
2017-06-21 23:35:03.675 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x00, 0xdc
2017-06-21 23:35:03.675 Detail, 
2017-06-21 23:35:03.676 Info, Node008, Response RTT 121 Average Response RTT 130
2017-06-21 23:35:03.676 Info, Node008, Received SwitchMultiLevel report: level=0
2017-06-21 23:35:03.676 Detail, Node008, Refreshed Value: old value=0, new value=0, type=byte
2017-06-21 23:35:03.676 Detail, Node008, Changes to this value are not verified
2017-06-21 23:35:03.676 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:35:03.676 Detail, Node008,   Message transaction complete
2017-06-21 23:35:03.676 Detail, 
2017-06-21 23:35:03.676 Detail, Node008, Removing current message
2017-06-21 23:35:03.676 Detail, Node008, Notification: ValueChanged
2017-06-21 23:35:36.875 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - �
2017-06-21 23:35:36.876 Info, Node008, SwitchMultilevel::Set - Setting to level 255
2017-06-21 23:35:36.876 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0xff, 0x25, 0x5f, 0x4f
2017-06-21 23:35:36.876 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x60, 0x8e
2017-06-21 23:35:36.876 Detail, 
2017-06-21 23:35:36.876 Info, Node008, Sending (Send) message (Callback ID=0x5f, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0xff, 0x25, 0x5f, 0x4f
2017-06-21 23:35:36.885 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:36.885 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:36.927 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x5f, 0x00, 0x00, 0x05, 0xb1
2017-06-21 23:35:36.927 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x5f received (expected 0x5f)
2017-06-21 23:35:36.928 Info, Node008, Request RTT 51 Average Request RTT 51
2017-06-21 23:35:36.928 Detail,   Expected callbackId was received
2017-06-21 23:35:36.928 Detail,   Expected reply was received
2017-06-21 23:35:36.928 Detail,   Message transaction complete
2017-06-21 23:35:36.928 Detail, 
2017-06-21 23:35:36.928 Detail, Node008, Removing current message
2017-06-21 23:35:36.928 Detail, 
2017-06-21 23:35:36.928 Info, Node008, Sending (Send) message (Callback ID=0x60, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x60, 0x8e
2017-06-21 23:35:36.937 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:36.937 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:37.001 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x60, 0x00, 0x00, 0x07, 0x8c
2017-06-21 23:35:37.002 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x60 received (expected 0x60)
2017-06-21 23:35:37.002 Info, Node008, Request RTT 74 Average Request RTT 62
2017-06-21 23:35:37.002 Detail,   Expected callbackId was received
2017-06-21 23:35:37.075 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x02, 0xde
2017-06-21 23:35:37.076 Detail, 
2017-06-21 23:35:37.076 Info, Node008, Response RTT 148 Average Response RTT 139
2017-06-21 23:35:37.076 Info, Node008, Received SwitchMultiLevel report: level=2
2017-06-21 23:35:37.076 Detail, Node008, Refreshed Value: old value=0, new value=2, type=byte
2017-06-21 23:35:37.076 Detail, Node008, Changes to this value are not verified
2017-06-21 23:35:37.076 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:35:37.076 Detail, Node008,   Message transaction complete
2017-06-21 23:35:37.076 Detail, 
2017-06-21 23:35:37.076 Detail, Node008, Removing current message
2017-06-21 23:35:37.077 Detail, Node008, Notification: ValueChanged
2017-06-21 23:35:52.291 Info, Node008, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 
2017-06-21 23:35:52.291 Info, Node008, SwitchMultilevel::Set - Setting to level 0
2017-06-21 23:35:52.291 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x61, 0x8e
2017-06-21 23:35:52.291 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x62, 0x8c
2017-06-21 23:35:52.291 Detail, 
2017-06-21 23:35:52.291 Info, Node008, Sending (Send) message (Callback ID=0x61, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=8): 0x01, 0x0a, 0x00, 0x13, 0x08, 0x03, 0x26, 0x01, 0x00, 0x25, 0x61, 0x8e
2017-06-21 23:35:52.300 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:52.300 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:52.342 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x61, 0x00, 0x00, 0x04, 0x8e
2017-06-21 23:35:52.342 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x61 received (expected 0x61)
2017-06-21 23:35:52.343 Info, Node008, Request RTT 51 Average Request RTT 56
2017-06-21 23:35:52.343 Detail,   Expected callbackId was received
2017-06-21 23:35:52.343 Detail,   Expected reply was received
2017-06-21 23:35:52.343 Detail,   Message transaction complete
2017-06-21 23:35:52.343 Detail, 
2017-06-21 23:35:52.343 Detail, Node008, Removing current message
2017-06-21 23:35:52.343 Detail, 
2017-06-21 23:35:52.343 Info, Node008, Sending (Send) message (Callback ID=0x62, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x62, 0x8c
2017-06-21 23:35:52.352 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:52.352 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:52.419 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x62, 0x00, 0x00, 0x07, 0x8e
2017-06-21 23:35:52.419 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x62 received (expected 0x62)
2017-06-21 23:35:52.419 Info, Node008, Request RTT 75 Average Request RTT 65
2017-06-21 23:35:52.419 Detail,   Expected callbackId was received
2017-06-21 23:35:52.495 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x62, 0xbe
2017-06-21 23:35:52.495 Detail, 
2017-06-21 23:35:52.496 Info, Node008, Response RTT 151 Average Response RTT 145
2017-06-21 23:35:52.496 Info, Node008, Received SwitchMultiLevel report: level=98
2017-06-21 23:35:52.496 Detail, Node008, Refreshed Value: old value=2, new value=98, type=byte
2017-06-21 23:35:52.496 Detail, Node008, Changes to this value are not verified
2017-06-21 23:35:52.496 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:35:52.496 Detail, Node008,   Message transaction complete
2017-06-21 23:35:52.496 Detail, 
2017-06-21 23:35:52.496 Detail, Node008, Removing current message
2017-06-21 23:35:52.496 Detail, Node008, Notification: ValueChanged
2017-06-21 23:35:53.292 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-21 23:35:53.292 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x63, 0x8d
2017-06-21 23:35:53.293 Detail, 
2017-06-21 23:35:53.293 Info, Node008, Sending (Send) message (Callback ID=0x63, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x63, 0x8d
2017-06-21 23:35:53.302 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:53.302 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:53.343 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x63, 0x00, 0x00, 0x04, 0x8c
2017-06-21 23:35:53.343 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x63 received (expected 0x63)
2017-06-21 23:35:53.343 Info, Node008, Request RTT 50 Average Request RTT 57
2017-06-21 23:35:53.343 Detail,   Expected callbackId was received
2017-06-21 23:35:53.416 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x42, 0x9e
2017-06-21 23:35:53.416 Detail, 
2017-06-21 23:35:53.416 Info, Node008, Response RTT 122 Average Response RTT 133
2017-06-21 23:35:53.416 Info, Node008, Received SwitchMultiLevel report: level=66
2017-06-21 23:35:53.416 Detail, Node008, Refreshed Value: old value=98, new value=66, type=byte
2017-06-21 23:35:53.416 Detail, Node008, Changes to this value are not verified
2017-06-21 23:35:53.416 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:35:53.416 Detail, Node008,   Message transaction complete
2017-06-21 23:35:53.416 Detail, 
2017-06-21 23:35:53.416 Detail, Node008, Removing current message
2017-06-21 23:35:53.417 Detail, Node008, Notification: ValueChanged
2017-06-21 23:35:54.293 Info, mgr,     Refreshing node 8: COMMAND_CLASS_SWITCH_MULTILEVEL index = 0 instance = 1 (to confirm a reported change)
2017-06-21 23:35:54.293 Detail, Node008, Queuing (Send) SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x64, 0x8a
2017-06-21 23:35:54.293 Detail, 
2017-06-21 23:35:54.293 Info, Node008, Sending (Send) message (Callback ID=0x64, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=8): 0x01, 0x09, 0x00, 0x13, 0x08, 0x02, 0x26, 0x02, 0x25, 0x64, 0x8a
2017-06-21 23:35:54.302 Detail, Node008,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2017-06-21 23:35:54.302 Detail, Node008,   ZW_SEND_DATA delivered to Z-Wave stack
2017-06-21 23:35:54.343 Detail, Node008,   Received: 0x01, 0x07, 0x00, 0x13, 0x64, 0x00, 0x00, 0x04, 0x8b
2017-06-21 23:35:54.344 Detail, Node008,   ZW_SEND_DATA Request with callback ID 0x64 received (expected 0x64)
2017-06-21 23:35:54.344 Info, Node008, Request RTT 50 Average Request RTT 53
2017-06-21 23:35:54.344 Detail,   Expected callbackId was received
2017-06-21 23:35:54.415 Detail, Node008,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x08, 0x03, 0x26, 0x03, 0x21, 0xfd
2017-06-21 23:35:54.415 Detail, 
2017-06-21 23:35:54.415 Info, Node008, Response RTT 121 Average Response RTT 127
2017-06-21 23:35:54.416 Info, Node008, Received SwitchMultiLevel report: level=33
2017-06-21 23:35:54.416 Detail, Node008, Refreshed Value: old value=66, new value=33, type=byte
2017-06-21 23:35:54.416 Detail, Node008, Changes to this value are not verified
2017-06-21 23:35:54.416 Detail, Node008,   Expected reply and command class was received
2017-06-21 23:35:54.416 Detail, Node008,   Message transaction complete
2017-06-21 23:35:54.416 Detail, 
2017-06-21 23:35:54.416 Detail, Node008, Removing current message
2017-06-21 23:35:54.416 Detail, Node008, Notification: ValueChanged
2017-06-21 23:37:28.628 Detail, Node007,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x07, 0x05, 0x31, 0x05, 0x01, 0x09, 0x4d, 0x83
2017-06-21 23:37:28.628 Detail, 
2017-06-21 23:37:28.629 Info, Node007, Received SensorMultiLevel report from node 7, instance 1, Temperature: value=77F
2017-06-21 23:37:28.629 Detail, Node007, Refreshed Value: old value=78, new value=77, type=decimal
2017-06-21 23:37:28.629 Detail, Node007, Changes to this value are not verified
2017-06-21 23:37:28.629 Detail, Node007, Notification: ValueChanged

@balloobbot
Copy link

There hasn't been any activity on this issue recently. Due to the high number of incoming GitHub notifications, we have to clean some of the old issues, as many of them have already been resolved with the latest updates.

Please make sure to update to the latest Home Assistant version and check if that solves the issue. Let us know if that works for you by adding a comment 👍

@balloobbot
Copy link

This issue will be auto-closed because there hasn't been any activity for a few months. Feel free to open a new one if you still experience this problem 👍

@home-assistant home-assistant locked and limited conversation to collaborators Mar 3, 2018
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Projects
None yet
Development

No branches or pull requests

3 participants