Zwave2mqtt: [question] Constant spam from a node after a period of time

[hyperbolic2346]

I'm seeing an issue with my zwave network. I'm hoping that this has been seen before. Everything is normal for a "while", which can be minutes or a couple of days. At that point, the network becomes extremely sluggish and lights take many seconds to respond. This gets progressively worse until the network is essentially not working. I found that it appears to be consumed by these messages:

2020-09-27T04:42:04.815Z z2m:Zwave zwave node 25: changed: 37-1-1:Target State:true -> true
2020-09-27 00:42:04.822 Detail, Node025,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-27 00:42:04.823 Detail, Node025,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-27 00:42:04.880 Info, Timer: delayed event
2020-09-27 00:42:04.880 Info, Node025, Sending Get to Refresh Value after Target Check
2020-09-27 00:42:04.880 Detail, Node025, Queuing (Send) SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x19, 0xe5
2020-09-27 00:42:04.880 Detail, Timer: waiting with timeout 184 ms
2020-09-27 00:42:04.932 Detail, Node025,   Received: 0x01, 0x07, 0x00, 0x13, 0x53, 0x00, 0x00, 0x0b, 0xb3
2020-09-27 00:42:04.932 Detail, Node025,   ZW_SEND_DATA Request with callback ID 0x53 received (expected 0x53)
2020-09-27 00:42:04.932 Info, Node025, Request RTT 117 Average Request RTT 115
2020-09-27 00:42:04.932 Detail, Node025,   Expected callbackId was received
2020-09-27 00:42:05.003 Detail, Node025,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x19, 0x03, 0x25, 0x03, 0x00, 0xce
2020-09-27 00:42:05.004 Detail,
2020-09-27 00:42:05.004 Info, Node025, Response RTT 191 Average Response RTT 187
2020-09-27 00:42:05.005 Info, Node025, Received SwitchBinary report from node 25: level=Off
2020-09-27 00:42:05.005 Detail, Node025, Value Updated: old value=true, new value=false, type=bool
2020-09-27 00:42:05.006 Detail, Node025,    Target Value is Set to true
2020-09-27 00:42:05.006 Info, Timer: adding event in 250 ms
2020-09-27 00:42:05.007 Detail, Node025, Value Updated: old value=true, new value=true, type=bool
2020-09-27 00:42:05.008 Detail, Node025, Changes to this value are not verified
2020-09-27 00:42:05.009 Detail, Timer: waiting with timeout 56 ms
2020-09-27 00:42:05.009 Detail, Node025,   Expected reply and command class was received
2020-09-27 00:42:05.010 Detail, Node025,   Message transaction complete
2020-09-27 00:42:05.011 Detail,
2020-09-27 00:42:05.011 Detail, Node025, Removing current message
2020-09-27 00:42:05.011 Detail, Node025, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-09-27 00:42:05.011 Detail,
2020-09-27 00:42:05.011 Info, Node025, Sending (Send) message (Callback ID=0x54, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x54, 0xa8
2020-09-27 00:42:05.011 Info, Node025, Encrypted Flag is 0
2020-09-27T04:42:05.017Z z2m:Zwave zwave node 25: changed: 37-1-1:Target State:true -> true
2020-09-27 00:42:05.019 Detail, Node025,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-27 00:42:05.021 Detail, Node025,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-27 00:42:05.065 Info, Timer: delayed event
2020-09-27 00:42:05.065 Info, Node025, Sending Get to Refresh Value after Target Check
2020-09-27 00:42:05.065 Detail, Node025, Queuing (Send) SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x1a, 0xe6
2020-09-27 00:42:05.065 Detail, Timer: waiting with timeout 192 ms
2020-09-27 00:42:05.122 Detail, Node025,   Received: 0x01, 0x07, 0x00, 0x13, 0x54, 0x00, 0x00, 0x0b, 0xb4
2020-09-27 00:42:05.123 Detail, Node025,   ZW_SEND_DATA Request with callback ID 0x54 received (expected 0x54)
2020-09-27 00:42:05.124 Info, Node025, Request RTT 112 Average Request RTT 113
2020-09-27 00:42:05.124 Detail, Node025,   Expected callbackId was received
2020-09-27 00:42:05.193 Detail, Node025,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x19, 0x03, 0x25, 0x03, 0x00, 0xce
2020-09-27 00:42:05.193 Detail,
2020-09-27 00:42:05.193 Info, Node025, Response RTT 181 Average Response RTT 184
2020-09-27 00:42:05.193 Info, Node025, Received SwitchBinary report from node 25: level=Off
2020-09-27 00:42:05.193 Detail, Node025, Value Updated: old value=true, new value=false, type=bool
2020-09-27 00:42:05.193 Detail, Node025,    Target Value is Set to true
2020-09-27 00:42:05.193 Info, Timer: adding event in 250 ms
2020-09-27 00:42:05.193 Detail, Node025, Value Updated: old value=true, new value=true, type=bool
2020-09-27 00:42:05.193 Detail, Node025, Changes to this value are not verified
2020-09-27 00:42:05.193 Detail, Node025,   Expected reply and command class was received
2020-09-27 00:42:05.193 Detail, Node025,   Message transaction complete
2020-09-27 00:42:05.193 Detail,
2020-09-27 00:42:05.193 Detail, Node025, Removing current message
2020-09-27 00:42:05.193 Detail, Timer: waiting with timeout 64 ms
2020-09-27 00:42:05.193 Detail, Node025, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-09-27 00:42:05.193 Detail,
2020-09-27 00:42:05.193 Info, Node025, Sending (Send) message (Callback ID=0x55, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x55, 0xa9
2020-09-27 00:42:05.193 Info, Node025, Encrypted Flag is 0
2020-09-27T04:42:05.194Z z2m:Zwave zwave node 25: changed: 37-1-1:Target State:true -> true
2020-09-27 00:42:05.203 Detail, Node025,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-27 00:42:05.203 Detail, Node025,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-27 00:42:05.257 Info, Timer: delayed event
2020-09-27 00:42:05.257 Info, Node025, Sending Get to Refresh Value after Target Check
2020-09-27 00:42:05.257 Detail, Node025, Queuing (Send) SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x1b, 0xe7
2020-09-27 00:42:05.257 Detail, Timer: waiting with timeout 185 ms
2020-09-27 00:42:05.312 Detail, Node025,   Received: 0x01, 0x07, 0x00, 0x13, 0x55, 0x00, 0x00, 0x0b, 0xb5
2020-09-27 00:42:05.312 Detail, Node025,   ZW_SEND_DATA Request with callback ID 0x55 received (expected 0x55)
2020-09-27 00:42:05.312 Info, Node025, Request RTT 119 Average Request RTT 116
2020-09-27 00:42:05.312 Detail, Node025,   Expected callbackId was received
2020-09-27 00:42:05.383 Detail, Node025,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x19, 0x03, 0x25, 0x03, 0x00, 0xce
2020-09-27 00:42:05.383 Detail,
2020-09-27 00:42:05.383 Info, Node025, Response RTT 189 Average Response RTT 186
2020-09-27 00:42:05.383 Info, Node025, Received SwitchBinary report from node 25: level=Off
2020-09-27 00:42:05.383 Detail, Node025, Value Updated: old value=true, new value=false, type=bool
2020-09-27 00:42:05.383 Detail, Node025,    Target Value is Set to true
2020-09-27 00:42:05.383 Info, Timer: adding event in 250 ms
2020-09-27 00:42:05.383 Detail, Node025, Value Updated: old value=true, new value=true, type=bool
2020-09-27 00:42:05.383 Detail, Node025, Changes to this value are not verified
2020-09-27 00:42:05.383 Detail, Node025,   Expected reply and command class was received
2020-09-27 00:42:05.383 Detail, Node025,   Message transaction complete
2020-09-27 00:42:05.383 Detail,
2020-09-27 00:42:05.383 Detail, Node025, Removing current message
2020-09-27 00:42:05.383 Detail, Node025, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-09-27 00:42:05.383 Detail,
2020-09-27 00:42:05.383 Info, Node025, Sending (Send) message (Callback ID=0x56, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x56, 0xaa
2020-09-27 00:42:05.383 Info, Node025, Encrypted Flag is 0
2020-09-27 00:42:05.384 Detail, Timer: waiting with timeout 58 ms
2020-09-27T04:42:05.384Z z2m:Zwave zwave node 25: changed: 37-1-1:Target State:true -> true
2020-09-27 00:42:05.393 Detail, Node025,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-27 00:42:05.393 Detail, Node025,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-27 00:42:05.443 Info, Timer: delayed event
2020-09-27 00:42:05.444 Info, Node025, Sending Get to Refresh Value after Target Check
2020-09-27 00:42:05.445 Detail, Node025, Queuing (Send) SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x1c, 0xe0
2020-09-27 00:42:05.445 Detail, Timer: waiting with timeout 187 ms
2020-09-27 00:42:05.599 Detail, Node025,   Received: 0x01, 0x07, 0x00, 0x13, 0x56, 0x00, 0x00, 0x0b, 0xb6
2020-09-27 00:42:05.599 Detail, Node025,   ZW_SEND_DATA Request with callback ID 0x56 received (expected 0x56)
2020-09-27 00:42:05.599 Info, Node025, Request RTT 215 Average Request RTT 165
2020-09-27 00:42:05.599 Detail, Node025,   Expected callbackId was received
2020-09-27 00:42:05.600 Detail, Node025,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x19, 0x03, 0x25, 0x03, 0x00, 0xce
2020-09-27 00:42:05.600 Detail,
2020-09-27 00:42:05.600 Info, Node025, Response RTT 216 Average Response RTT 201
2020-09-27 00:42:05.600 Info, Node025, Received SwitchBinary report from node 25: level=Off
2020-09-27 00:42:05.600 Detail, Node025, Value Updated: old value=true, new value=false, type=bool
2020-09-27 00:42:05.600 Detail, Node025,    Target Value is Set to true
2020-09-27 00:42:05.600 Info, Timer: adding event in 250 ms
2020-09-27 00:42:05.600 Detail, Node025, Value Updated: old value=true, new value=true, type=bool
2020-09-27 00:42:05.600 Detail, Node025, Changes to this value are not verified
2020-09-27 00:42:05.600 Detail, Node025,   Expected reply and command class was received
2020-09-27 00:42:05.600 Detail, Node025,   Message transaction complete
2020-09-27 00:42:05.600 Detail,
2020-09-27 00:42:05.600 Detail, Node025, Removing current message
2020-09-27 00:42:05.600 Detail, Node025, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-09-27 00:42:05.600 Detail,
2020-09-27 00:42:05.600 Info, Node025, Sending (Send) message (Callback ID=0x57, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x57, 0xab
2020-09-27 00:42:05.601 Info, Node025, Encrypted Flag is 0
2020-09-27T04:42:05.602Z z2m:Zwave zwave node 25: changed: 37-1-1:Target State:true -> true
2020-09-27 00:42:05.613 Detail, Node025,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-27 00:42:05.613 Detail, Node025,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-27 00:42:05.613 Detail, Timer: waiting with timeout 19 ms
2020-09-27 00:42:05.632 Info, Timer: delayed event
2020-09-27 00:42:05.632 Info, Node025, Sending Get to Refresh Value after Target Check
2020-09-27 00:42:05.632 Detail, Node025, Queuing (Send) SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x1d, 0xe1
2020-09-27 00:42:05.633 Detail, Timer: waiting with timeout 216 ms
2020-09-27 00:42:05.692 Detail, Node025,   Received: 0x01, 0x07, 0x00, 0x13, 0x57, 0x00, 0x00, 0x09, 0xb5
2020-09-27 00:42:05.693 Detail, Node025,   ZW_SEND_DATA Request with callback ID 0x57 received (expected 0x57)
2020-09-27 00:42:05.693 Info, Node025, Request RTT 91 Average Request RTT 128
2020-09-27 00:42:05.693 Detail, Node025,   Expected callbackId was received
2020-09-27 00:42:05.763 Detail, Node025,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x19, 0x03, 0x25, 0x03, 0x00, 0xce
2020-09-27 00:42:05.764 Detail,
2020-09-27 00:42:05.772 Info, Node025, Response RTT 171 Average Response RTT 186
2020-09-27 00:42:05.780 Info, Node025, Received SwitchBinary report from node 25: level=Off
2020-09-27 00:42:05.781 Detail, Node025, Value Updated: old value=true, new value=false, type=bool
2020-09-27 00:42:05.781 Detail, Node025,    Target Value is Set to true
2020-09-27 00:42:05.782 Info, Timer: adding event in 250 ms
2020-09-27 00:42:05.802 Detail, Node025, Value Updated: old value=true, new value=true, type=bool
2020-09-27 00:42:05.804 Detail, Node025, Changes to this value are not verified
2020-09-27 00:42:05.805 Detail, Timer: waiting with timeout 47 ms
2020-09-27 00:42:05.806 Detail, Node025,   Expected reply and command class was received
2020-09-27 00:42:05.806 Detail, Node025,   Message transaction complete
2020-09-27 00:42:05.808 Detail,
2020-09-27 00:42:05.808 Detail, Node025, Removing current message
2020-09-27 00:42:05.809 Detail, Node025, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-09-27 00:42:05.811 Detail,
2020-09-27 00:42:05.812 Info, Node025, Sending (Send) message (Callback ID=0x58, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x58, 0xa4
2020-09-27 00:42:05.813 Info, Node025, Encrypted Flag is 0
2020-09-27T04:42:05.814Z z2m:Zwave zwave node 25: changed: 37-1-1:Target State:true -> true
2020-09-27 00:42:05.822 Detail, Node025,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-27 00:42:05.823 Detail, Node025,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-27 00:42:05.853 Info, Timer: delayed event
2020-09-27 00:42:05.854 Info, Node025, Sending Get to Refresh Value after Target Check
2020-09-27 00:42:05.854 Detail, Node025, Queuing (Send) SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x1e, 0xe2
2020-09-27 00:42:05.855 Detail, Timer: waiting with timeout 198 ms
2020-09-27 00:42:05.882 Detail, Node025,   Received: 0x01, 0x07, 0x00, 0x13, 0x58, 0x00, 0x00, 0x07, 0xb4
2020-09-27 00:42:05.883 Detail, Node025,   ZW_SEND_DATA Request with callback ID 0x58 received (expected 0x58)
2020-09-27 00:42:05.884 Info, Node025, Request RTT 69 Average Request RTT 98
2020-09-27 00:42:05.885 Detail, Node025,   Expected callbackId was received
2020-09-27 00:42:05.953 Detail, Node025,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x19, 0x03, 0x25, 0x03, 0x00, 0xce
2020-09-27 00:42:05.953 Detail,
2020-09-27 00:42:05.954 Info, Node025, Response RTT 139 Average Response RTT 162
2020-09-27 00:42:05.954 Info, Node025, Received SwitchBinary report from node 25: level=Off
2020-09-27 00:42:05.954 Detail, Node025, Value Updated: old value=true, new value=false, type=bool
2020-09-27 00:42:05.954 Detail, Node025,    Target Value is Set to true
2020-09-27 00:42:05.954 Info, Timer: adding event in 250 ms
2020-09-27 00:42:05.954 Detail, Node025, Value Updated: old value=true, new value=true, type=bool
2020-09-27 00:42:05.954 Detail, Node025, Changes to this value are not verified
2020-09-27 00:42:05.954 Detail, Node025,   Expected reply and command class was received
2020-09-27 00:42:05.954 Detail, Node025,   Message transaction complete
2020-09-27 00:42:05.954 Detail,
2020-09-27 00:42:05.954 Detail, Node025, Removing current message
2020-09-27 00:42:05.954 Detail, Node025, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-09-27 00:42:05.955 Detail, Timer: waiting with timeout 99 ms
2020-09-27 00:42:05.955 Detail,
2020-09-27 00:42:05.955 Info, Node025, Sending (Send) message (Callback ID=0x59, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x59, 0xa5
2020-09-27 00:42:05.955 Info, Node025, Encrypted Flag is 0
2020-09-27T04:42:05.955Z z2m:Zwave zwave node 25: changed: 37-1-1:Target State:true -> true
2020-09-27 00:42:05.963 Detail, Node025,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-27 00:42:05.963 Detail, Node025,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-27 00:42:06.054 Info, Timer: delayed event
2020-09-27 00:42:06.054 Info, Node025, Sending Get to Refresh Value after Target Check
2020-09-27 00:42:06.054 Detail, Node025, Queuing (Send) SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x1f, 0xe3
2020-09-27 00:42:06.054 Detail, Timer: waiting with timeout 149 ms
2020-09-27 00:42:06.072 Detail, Node025,   Received: 0x01, 0x07, 0x00, 0x13, 0x59, 0x00, 0x00, 0x0b, 0xb9
2020-09-27 00:42:06.072 Detail, Node025,   ZW_SEND_DATA Request with callback ID 0x59 received (expected 0x59)
2020-09-27 00:42:06.072 Info, Node025, Request RTT 117 Average Request RTT 107
2020-09-27 00:42:06.072 Detail, Node025,   Expected callbackId was received
2020-09-27 00:42:06.143 Detail, Node025,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x19, 0x03, 0x25, 0x03, 0x00, 0xce
2020-09-27 00:42:06.143 Detail,
2020-09-27 00:42:06.143 Info, Node025, Response RTT 188 Average Response RTT 175
2020-09-27 00:42:06.143 Info, Node025, Received SwitchBinary report from node 25: level=Off
2020-09-27 00:42:06.143 Detail, Node025, Value Updated: old value=true, new value=false, type=bool
2020-09-27 00:42:06.143 Detail, Node025,    Target Value is Set to true
2020-09-27 00:42:06.143 Info, Timer: adding event in 250 ms
2020-09-27 00:42:06.143 Detail, Node025, Value Updated: old value=true, new value=true, type=bool
2020-09-27 00:42:06.143 Detail, Node025, Changes to this value are not verified
2020-09-27 00:42:06.143 Detail, Node025,   Expected reply and command class was received
2020-09-27 00:42:06.143 Detail, Node025,   Message transaction complete
2020-09-27 00:42:06.143 Detail,
2020-09-27 00:42:06.143 Detail, Node025, Removing current message
2020-09-27 00:42:06.143 Detail, Node025, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-09-27 00:42:06.143 Detail,
2020-09-27 00:42:06.143 Info, Node025, Sending (Send) message (Callback ID=0x5a, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x5a, 0xa6
2020-09-27 00:42:06.143 Info, Node025, Encrypted Flag is 0
2020-09-27 00:42:06.143 Detail, Timer: waiting with timeout 60 ms
2020-09-27T04:42:06.143Z z2m:Zwave zwave node 25: changed: 37-1-1:Target State:true -> true
2020-09-27 00:42:06.153 Detail, Node025,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-27 00:42:06.153 Detail, Node025,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-27 00:42:06.203 Info, Timer: delayed event
2020-09-27 00:42:06.204 Info, Node025, Sending Get to Refresh Value after Target Check
2020-09-27 00:42:06.204 Detail, Node025, Queuing (Send) SwitchBinaryCmd_Get (Node=25): 0x01, 0x09, 0x00, 0x13, 0x19, 0x02, 0x25, 0x02, 0x25, 0x20, 0xdc
2020-09-27 00:42:06.205 Detail, Timer: waiting with timeout 188 ms
2020-09-27 00:42:06.262 Detail, Node025,   Received: 0x01, 0x07, 0x00, 0x13, 0x5a, 0x00, 0x00, 0x0b, 0xba
2020-09-27 00:42:06.262 Detail, Node025,   ZW_SEND_DATA Request with callback ID 0x5a received (expected 0x5a)
2020-09-27 00:42:06.262 Info, Node025, Request RTT 118 Average Request RTT 112
2020-09-27 00:42:06.262 Detail, Node025,   Expected callbackId was received

Has anyone seen this before or have any ideas on how I can fix this? I saw this before with node 20 and removed and added the node again. This is a different node now doing this.

[robertsLando]

@hyperbolic2346 Do you have lot failed/dead nodes in your network? Have you enable polling of some values? I never saw such bug in my networks unfortunaly :( @Fishwaldo Do you know what could cause this kind of network 'saturation'?

[hyperbolic2346]

I have limited my network right now to 30 nodes. I have 4 that are listed as removed if I show hidden nodes. Everything else is alive or sleeping. The original node that this happened with is not 10 feet from the zwave stick. It seems to be stuck constantly trying to update the state of that node.

Looking at my options I do see a 5 second poll, which seems very fast to me. I've upped that to 300 seconds, which is probably too far the other way, but worth a shot for now. I would expect if it was polling that I would see all nodes equally represented in the traffic, but it was always a single node with constant updates. I would see zwave node 25: changed: 37-1-1:Target State:true -> true constantly in the log scrolling many times a second until I turned on library logging to try and figure out why the state was updating.

[robertsLando]

@hyperbolic2346 The poll time should be at least 1 second for node, not less, that could be the reason

[hyperbolic2346]

This is fascinating. So tonight I happened to be looking at my logs and toggled a switch. When I turn the light on there is no unusual chatter, but when I turn it off there is constant spam.

2020-10-02T03:58:31.068Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-02T03:59:02.967Z z2m:App Zwave api call: setValue [ 30, 37, 1, 0, false ]
2020-10-01 23:59:02.968 Info, Node030, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2020-10-01 23:59:02.969 Info, Node030, SwitchBinary::Set - Setting to Off
2020-10-01 23:59:02.969 Info, Node030,   Duration: Device Default
2020-10-01 23:59:02.969 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Set (Node=30): 0x01, 0x0b, 0x00, 0x13, 0x1e, 0x04, 0x25, 0x01, 0x00, 0xff, 0x25, 0xbc, 0xbf
2020-10-01 23:59:02.970 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xbd, 0x46
2020-10-01 23:59:02.970 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-01 23:59:02.970 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-02T03:59:02.971Z z2m:Zwave Success zwave api call setValue
2020-10-01 23:59:02.971 Detail,
2020-10-01 23:59:02.972 Info, Node030, Sending (Send) message (Callback ID=0xbc, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=30): 0x01, 0x0b, 0x00, 0x13, 0x1e, 0x04, 0x25, 0x01, 0x00, 0xff, 0x25, 0xbc, 0xbf
2020-10-01 23:59:02.972 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:02.979 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:02.979 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:03.001 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xbc, 0x00, 0x00, 0x03, 0x54
2020-10-01 23:59:03.001 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xbc received (expected 0xbc)
2020-10-01 23:59:03.002 Info, Node030, Request RTT 31 Average Request RTT 29
2020-10-01 23:59:03.002 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:03.003 Detail, Node030,   Expected reply was received
2020-10-01 23:59:03.003 Detail, Node030,   Message transaction complete
2020-10-01 23:59:03.004 Detail,
2020-10-01 23:59:03.004 Detail, Node030, Removing current message
2020-10-01 23:59:03.004 Detail,
2020-10-01 23:59:03.004 Info, Node030, Sending (Send) message (Callback ID=0xbd, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xbd, 0x46
2020-10-01 23:59:03.004 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:03.012 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:03.012 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:03.033 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xbd, 0x00, 0x00, 0x02, 0x54
2020-10-01 23:59:03.033 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xbd received (expected 0xbd)
2020-10-01 23:59:03.033 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:03.033 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:03.047 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:03.047 Detail,
2020-10-01 23:59:03.047 Info, Node030, Response RTT 42 Average Response RTT 42
2020-10-01 23:59:03.047 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:03.048 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:03.048 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:03.048 Info, Timer: adding event in 250 ms
2020-10-01 23:59:03.048 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:03.048 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:03.049 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:03.049 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:03.049 Detail, Node030,   Message transaction complete
2020-10-01 23:59:03.049 Detail,
2020-10-01 23:59:03.049 Detail, Node030, Removing current message
2020-10-01 23:59:03.049 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:03.050Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:03.298 Info, Timer: delayed event
2020-10-01 23:59:03.298 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:03.298 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xbe, 0x45
2020-10-01 23:59:03.298 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:03.299 Detail,
2020-10-01 23:59:03.300 Info, Node030, Sending (Send) message (Callback ID=0xbe, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xbe, 0x45
2020-10-01 23:59:03.300 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:03.308 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:03.308 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:03.329 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xbe, 0x00, 0x00, 0x03, 0x56
2020-10-01 23:59:03.330 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xbe received (expected 0xbe)
2020-10-01 23:59:03.330 Info, Node030, Request RTT 29 Average Request RTT 28
2020-10-01 23:59:03.331 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:03.344 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:03.344 Detail,
2020-10-01 23:59:03.345 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:03.345 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:03.345 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:03.345 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:03.346 Info, Timer: adding event in 250 ms
2020-10-01 23:59:03.346 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:03.346 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:03.346 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:03.346 Detail, Node030,   Message transaction complete
2020-10-01 23:59:03.347 Detail,
2020-10-01 23:59:03.347 Detail, Node030, Removing current message
2020-10-01 23:59:03.347 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-01 23:59:03.347 Detail, Timer: waiting with timeout 248 ms
2020-10-02T03:59:03.347Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:03.596 Info, Timer: delayed event
2020-10-01 23:59:03.597 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:03.598 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xbf, 0x44
2020-10-01 23:59:03.600 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:03.601 Detail,
2020-10-01 23:59:03.601 Info, Node030, Sending (Send) message (Callback ID=0xbf, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xbf, 0x44
2020-10-01 23:59:03.601 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:03.608 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:03.609 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:03.629 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xbf, 0x00, 0x00, 0x03, 0x57
2020-10-01 23:59:03.629 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xbf received (expected 0xbf)
2020-10-01 23:59:03.630 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:03.631 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:03.643 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:03.643 Detail,
2020-10-01 23:59:03.644 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:03.644 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:03.645 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:03.645 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:03.645 Info, Timer: adding event in 250 ms
2020-10-01 23:59:03.646 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:03.646 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:03.647 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:03.647 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:03.647 Detail, Node030,   Message transaction complete
2020-10-01 23:59:03.648 Detail,
2020-10-01 23:59:03.648 Detail, Node030, Removing current message
2020-10-01 23:59:03.649 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:03.649Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:03.895 Info, Timer: delayed event
2020-10-01 23:59:03.895 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:03.896 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc0, 0x3b
2020-10-01 23:59:03.896 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:03.896 Detail,
2020-10-01 23:59:03.896 Info, Node030, Sending (Send) message (Callback ID=0xc0, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc0, 0x3b
2020-10-01 23:59:03.896 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:03.903 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:03.903 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:03.924 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc0, 0x00, 0x00, 0x02, 0x29
2020-10-01 23:59:03.924 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc0 received (expected 0xc0)
2020-10-01 23:59:03.924 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:03.924 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:03.938 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:03.939 Detail,
2020-10-01 23:59:03.939 Info, Node030, Response RTT 42 Average Response RTT 42
2020-10-01 23:59:03.939 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:03.939 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:03.939 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:03.939 Info, Timer: adding event in 250 ms
2020-10-01 23:59:03.939 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:03.939 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:03.939 Detail, Timer: waiting with timeout 250 ms
2020-10-01 23:59:03.939 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:03.939 Detail, Node030,   Message transaction complete
2020-10-01 23:59:03.939 Detail,
2020-10-01 23:59:03.939 Detail, Node030, Removing current message
2020-10-01 23:59:03.939 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:03.940Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:04.189 Info, Timer: delayed event
2020-10-01 23:59:04.189 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:04.189 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc1, 0x3a
2020-10-01 23:59:04.189 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:04.189 Detail,
2020-10-01 23:59:04.190 Info, Node030, Sending (Send) message (Callback ID=0xc1, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc1, 0x3a
2020-10-01 23:59:04.190 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:04.197 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:04.198 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:04.219 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc1, 0x00, 0x00, 0x03, 0x29
2020-10-01 23:59:04.220 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc1 received (expected 0xc1)
2020-10-01 23:59:04.221 Info, Node030, Request RTT 30 Average Request RTT 29
2020-10-01 23:59:04.222 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:04.232 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:04.233 Detail,
2020-10-01 23:59:04.233 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:04.233 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:04.234 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:04.234 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:04.234 Info, Timer: adding event in 250 ms
2020-10-01 23:59:04.234 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:04.235 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:04.235 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:04.235 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:04.236 Detail, Node030,   Message transaction complete
2020-10-01 23:59:04.237 Detail,
2020-10-01 23:59:04.237 Detail, Node030, Removing current message
2020-10-01 23:59:04.237 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:04.238Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:04.484 Info, Timer: delayed event
2020-10-01 23:59:04.484 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:04.484 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc2, 0x39
2020-10-01 23:59:04.484 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:04.485 Detail,
2020-10-01 23:59:04.485 Info, Node030, Sending (Send) message (Callback ID=0xc2, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc2, 0x39
2020-10-01 23:59:04.485 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:04.492 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:04.492 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:04.513 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc2, 0x00, 0x00, 0x02, 0x2b
2020-10-01 23:59:04.513 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc2 received (expected 0xc2)
2020-10-01 23:59:04.514 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:04.514 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:04.527 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:04.528 Detail,
2020-10-01 23:59:04.529 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:04.529 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:04.530 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:04.530 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:04.530 Info, Timer: adding event in 250 ms
2020-10-01 23:59:04.531 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:04.531 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:04.532 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:04.532 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:04.533 Detail, Node030,   Message transaction complete
2020-10-01 23:59:04.533 Detail,
2020-10-01 23:59:04.533 Detail, Node030, Removing current message
2020-10-01 23:59:04.534 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:04.535Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:04.781 Info, Timer: delayed event
2020-10-01 23:59:04.781 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:04.781 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc3, 0x38
2020-10-01 23:59:04.781 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:04.782 Detail,
2020-10-01 23:59:04.783 Info, Node030, Sending (Send) message (Callback ID=0xc3, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc3, 0x38
2020-10-01 23:59:04.784 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:04.792 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:04.792 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:04.813 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc3, 0x00, 0x00, 0x02, 0x2a
2020-10-01 23:59:04.813 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc3 received (expected 0xc3)
2020-10-01 23:59:04.814 Info, Node030, Request RTT 29 Average Request RTT 28
2020-10-01 23:59:04.815 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:04.827 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:04.827 Detail,
2020-10-01 23:59:04.827 Info, Node030, Response RTT 42 Average Response RTT 42
2020-10-01 23:59:04.827 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:04.828 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:04.828 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:04.828 Info, Timer: adding event in 250 ms
2020-10-01 23:59:04.829 Detail, Timer: waiting with timeout 250 ms
2020-10-01 23:59:04.829 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:04.829 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:04.830 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:04.830 Detail, Node030,   Message transaction complete
2020-10-01 23:59:04.830 Detail,
2020-10-01 23:59:04.831 Detail, Node030, Removing current message
2020-10-01 23:59:04.831 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:04.832Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:05.079 Info, Timer: delayed event
2020-10-01 23:59:05.080 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:05.080 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc4, 0x3f
2020-10-01 23:59:05.080 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:05.081 Detail,
2020-10-01 23:59:05.082 Info, Node030, Sending (Send) message (Callback ID=0xc4, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc4, 0x3f
2020-10-01 23:59:05.083 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:05.092 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:05.092 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:05.113 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc4, 0x00, 0x00, 0x02, 0x2d
2020-10-01 23:59:05.114 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc4 received (expected 0xc4)
2020-10-01 23:59:05.114 Info, Node030, Request RTT 29 Average Request RTT 28
2020-10-01 23:59:05.115 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:05.127 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:05.128 Detail,
2020-10-01 23:59:05.129 Info, Node030, Response RTT 44 Average Response RTT 43
2020-10-01 23:59:05.130 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:05.131 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:05.131 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:05.131 Info, Timer: adding event in 250 ms
2020-10-01 23:59:05.131 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:05.131 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:05.131 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:05.132 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:05.132 Detail, Node030,   Message transaction complete
2020-10-01 23:59:05.132 Detail,
2020-10-01 23:59:05.132 Detail, Node030, Removing current message
2020-10-01 23:59:05.133 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:05.134Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:05.381 Info, Timer: delayed event
2020-10-01 23:59:05.381 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:05.382 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc5, 0x3e
2020-10-01 23:59:05.382 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:05.382 Detail,
2020-10-01 23:59:05.383 Info, Node030, Sending (Send) message (Callback ID=0xc5, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc5, 0x3e
2020-10-01 23:59:05.383 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:05.391 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:05.392 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:05.412 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc5, 0x00, 0x00, 0x02, 0x2c
2020-10-01 23:59:05.412 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc5 received (expected 0xc5)
2020-10-01 23:59:05.412 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:05.412 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:05.426 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:05.427 Detail,
2020-10-01 23:59:05.427 Info, Node030, Response RTT 43 Average Response RTT 43
2020-10-01 23:59:05.428 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:05.428 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:05.428 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:05.429 Info, Timer: adding event in 250 ms
2020-10-01 23:59:05.429 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:05.430 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:05.430 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:05.431 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:05.431 Detail, Node030,   Message transaction complete
2020-10-01 23:59:05.431 Detail,
2020-10-01 23:59:05.432 Detail, Node030, Removing current message
2020-10-01 23:59:05.432 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:05.433Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:05.679 Info, Timer: delayed event
2020-10-01 23:59:05.680 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:05.681 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc6, 0x3d
2020-10-01 23:59:05.682 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:05.684 Detail,
2020-10-01 23:59:05.685 Info, Node030, Sending (Send) message (Callback ID=0xc6, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc6, 0x3d
2020-10-01 23:59:05.685 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:05.693 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:05.693 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:05.714 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc6, 0x00, 0x00, 0x02, 0x2f
2020-10-01 23:59:05.714 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc6 received (expected 0xc6)
2020-10-01 23:59:05.714 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:05.714 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:05.728 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:05.728 Detail,
2020-10-01 23:59:05.728 Info, Node030, Response RTT 42 Average Response RTT 42
2020-10-01 23:59:05.728 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:05.728 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:05.728 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:05.728 Info, Timer: adding event in 250 ms
2020-10-01 23:59:05.728 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:05.728 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:05.729 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:05.729 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:05.729 Detail, Node030,   Message transaction complete
2020-10-01 23:59:05.729 Detail,
2020-10-01 23:59:05.729 Detail, Node030, Removing current message
2020-10-01 23:59:05.729 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:05.730Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:05.978 Info, Timer: delayed event
2020-10-01 23:59:05.979 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:05.980 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc7, 0x3c
2020-10-01 23:59:05.982 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:05.984 Detail,
2020-10-01 23:59:05.984 Info, Node030, Sending (Send) message (Callback ID=0xc7, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc7, 0x3c
2020-10-01 23:59:05.984 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:05.992 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:05.992 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:06.013 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc7, 0x00, 0x00, 0x02, 0x2e
2020-10-01 23:59:06.014 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc7 received (expected 0xc7)
2020-10-01 23:59:06.024 Info, Node030, Request RTT 40 Average Request RTT 34
2020-10-01 23:59:06.025 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:06.027 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:06.027 Detail,
2020-10-01 23:59:06.027 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:06.027 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:06.027 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:06.027 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:06.027 Info, Timer: adding event in 250 ms
2020-10-01 23:59:06.027 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:06.027 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:06.028 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:06.028 Detail, Node030,   Message transaction complete
2020-10-01 23:59:06.028 Detail,
2020-10-01 23:59:06.028 Detail, Node030, Removing current message
2020-10-01 23:59:06.028 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-01 23:59:06.028 Detail, Timer: waiting with timeout 249 ms
2020-10-02T03:59:06.028Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:06.277 Info, Timer: delayed event
2020-10-01 23:59:06.277 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:06.277 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc8, 0x33
2020-10-01 23:59:06.277 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:06.277 Detail,
2020-10-01 23:59:06.278 Info, Node030, Sending (Send) message (Callback ID=0xc8, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc8, 0x33
2020-10-01 23:59:06.278 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:06.285 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:06.285 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:06.306 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc8, 0x00, 0x00, 0x03, 0x20
2020-10-01 23:59:06.308 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc8 received (expected 0xc8)
2020-10-01 23:59:06.309 Info, Node030, Request RTT 31 Average Request RTT 32
2020-10-01 23:59:06.311 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:06.321 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:06.321 Detail,
2020-10-01 23:59:06.321 Info, Node030, Response RTT 42 Average Response RTT 42
2020-10-01 23:59:06.321 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:06.321 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:06.321 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:06.321 Info, Timer: adding event in 250 ms
2020-10-01 23:59:06.321 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:06.321 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:06.321 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:06.321 Detail, Node030,   Message transaction complete
2020-10-01 23:59:06.321 Detail,
2020-10-01 23:59:06.321 Detail, Node030, Removing current message
2020-10-01 23:59:06.321 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:06.321 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:06.322Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:06.571 Info, Timer: delayed event
2020-10-01 23:59:06.571 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:06.571 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc9, 0x32
2020-10-01 23:59:06.571 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:06.571 Detail,
2020-10-01 23:59:06.571 Info, Node030, Sending (Send) message (Callback ID=0xc9, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xc9, 0x32
2020-10-01 23:59:06.571 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:06.578 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:06.579 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:06.600 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xc9, 0x00, 0x00, 0x03, 0x21
2020-10-01 23:59:06.600 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xc9 received (expected 0xc9)
2020-10-01 23:59:06.600 Info, Node030, Request RTT 28 Average Request RTT 30
2020-10-01 23:59:06.600 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:06.614 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:06.614 Detail,
2020-10-01 23:59:06.615 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:06.615 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:06.616 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:06.617 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:06.618 Info, Timer: adding event in 250 ms
2020-10-01 23:59:06.619 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:06.620 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:06.620 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:06.621 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:06.622 Detail, Node030,   Message transaction complete
2020-10-01 23:59:06.623 Detail,
2020-10-01 23:59:06.623 Detail, Node030, Removing current message
2020-10-01 23:59:06.623 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:06.623Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-02T03:59:06.696Z z2m:App Zwave api call: setValue [ 30, 37, 1, 0, true ]
2020-10-01 23:59:06.697 Info, Node030, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - True
2020-10-01 23:59:06.697 Info, Node030, SwitchBinary::Set - Setting to On
2020-10-01 23:59:06.697 Info, Node030,   Duration: Device Default
2020-10-01 23:59:06.698 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Set (Node=30): 0x01, 0x0b, 0x00, 0x13, 0x1e, 0x04, 0x25, 0x01, 0xff, 0xff, 0x25, 0xca, 0x36
2020-10-01 23:59:06.699 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xcb, 0x30
2020-10-01 23:59:06.699 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-01 23:59:06.700 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-02T03:59:06.700Z z2m:Zwave Success zwave api call setValue
2020-10-01 23:59:06.700 Detail,
2020-10-01 23:59:06.701 Info, Node030, Sending (Send) message (Callback ID=0xca, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=30): 0x01, 0x0b, 0x00, 0x13, 0x1e, 0x04, 0x25, 0x01, 0xff, 0xff, 0x25, 0xca, 0x36
2020-10-01 23:59:06.701 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:06.708 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:06.708 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:06.729 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xca, 0x00, 0x00, 0x03, 0x22
2020-10-01 23:59:06.730 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xca received (expected 0xca)
2020-10-01 23:59:06.730 Info, Node030, Request RTT 29 Average Request RTT 29
2020-10-01 23:59:06.731 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:06.731 Detail, Node030,   Expected reply was received
2020-10-01 23:59:06.731 Detail, Node030,   Message transaction complete
2020-10-01 23:59:06.731 Detail,
2020-10-01 23:59:06.732 Detail, Node030, Removing current message
2020-10-01 23:59:06.732 Detail,
2020-10-01 23:59:06.733 Info, Node030, Sending (Send) message (Callback ID=0xcb, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xcb, 0x30
2020-10-01 23:59:06.733 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:06.741 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:06.741 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:06.762 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xcb, 0x00, 0x00, 0x02, 0x22
2020-10-01 23:59:06.762 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xcb received (expected 0xcb)
2020-10-01 23:59:06.762 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:06.763 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:06.774 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0xff, 0x36
2020-10-01 23:59:06.775 Detail,
2020-10-01 23:59:06.775 Info, Node030, Response RTT 41 Average Response RTT 41
2020-10-01 23:59:06.775 Info, Node030, Received SwitchBinary report from node 30: level=On
2020-10-01 23:59:06.775 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:06.775 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:06.776 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:06.776 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:06.776 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:06.776 Detail, Node030,   Message transaction complete
2020-10-01 23:59:06.777 Detail,
2020-10-01 23:59:06.777 Detail, Node030, Removing current message
2020-10-01 23:59:06.777 Detail, Node030, Notification: ValueChanged CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 0
2020-10-01 23:59:06.777 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:06.778Z z2m:Zwave zwave node 30: changed: 37-1-0:Switch:true -> true
2020-10-02T03:59:06.778Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:06.869 Detail, Timer: waiting with timeout -1 ms
2020-10-02T03:59:12.894Z z2m:App Zwave api call: setValue [ 30, 37, 1, 0, false ]
2020-10-01 23:59:12.897 Info, Node030, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2020-10-01 23:59:12.898 Info, Node030, SwitchBinary::Set - Setting to Off
2020-10-01 23:59:12.899 Info, Node030,   Duration: Device Default
2020-10-01 23:59:12.899 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Set (Node=30): 0x01, 0x0b, 0x00, 0x13, 0x1e, 0x04, 0x25, 0x01, 0x00, 0xff, 0x25, 0xcc, 0xcf
2020-10-01 23:59:12.900 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xcd, 0x36
2020-10-01 23:59:12.900 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-01 23:59:12.901 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-01 23:59:12.901 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-01 23:59:12.901 Detail,
2020-10-01 23:59:12.902 Info, Node030, Sending (Send) message (Callback ID=0xcc, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=30): 0x01, 0x0b, 0x00, 0x13, 0x1e, 0x04, 0x25, 0x01, 0x00, 0xff, 0x25, 0xcc, 0xcf
2020-10-01 23:59:12.902 Info, Node030, Encrypted Flag is 0
2020-10-02T03:59:12.902Z z2m:Zwave Success zwave api call setValue
2020-10-01 23:59:12.909 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:12.909 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:12.931 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xcc, 0x00, 0x00, 0x03, 0x24
2020-10-01 23:59:12.931 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xcc received (expected 0xcc)
2020-10-01 23:59:12.932 Info, Node030, Request RTT 29 Average Request RTT 28
2020-10-01 23:59:12.932 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:12.933 Detail, Node030,   Expected reply was received
2020-10-01 23:59:12.933 Detail, Node030,   Message transaction complete
2020-10-01 23:59:12.934 Detail,
2020-10-01 23:59:12.934 Detail, Node030, Removing current message
2020-10-01 23:59:12.934 Detail,
2020-10-01 23:59:12.935 Info, Node030, Sending (Send) message (Callback ID=0xcd, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xcd, 0x36
2020-10-01 23:59:12.935 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:12.943 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:12.943 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:12.964 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xcd, 0x00, 0x00, 0x02, 0x24
2020-10-01 23:59:12.964 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xcd received (expected 0xcd)
2020-10-01 23:59:12.965 Info, Node030, Request RTT 29 Average Request RTT 28
2020-10-01 23:59:12.966 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:12.979 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:12.979 Detail,
2020-10-01 23:59:12.980 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:12.980 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:12.981 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:12.982 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:12.983 Info, Timer: adding event in 250 ms
2020-10-01 23:59:12.984 Detail, Timer: waiting with timeout 250 ms
2020-10-01 23:59:12.984 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:12.985 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:12.985 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:12.985 Detail, Node030,   Message transaction complete
2020-10-01 23:59:12.986 Detail,
2020-10-01 23:59:12.986 Detail, Node030, Removing current message
2020-10-01 23:59:12.986 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:12.987Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:13.234 Info, Timer: delayed event
2020-10-01 23:59:13.235 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:13.236 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xce, 0x35
2020-10-01 23:59:13.237 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:13.238 Detail,
2020-10-01 23:59:13.239 Info, Node030, Sending (Send) message (Callback ID=0xce, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xce, 0x35
2020-10-01 23:59:13.240 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:13.247 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:13.248 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:13.268 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xce, 0x00, 0x00, 0x03, 0x26
2020-10-01 23:59:13.268 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xce received (expected 0xce)
2020-10-01 23:59:13.268 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:13.268 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:13.282 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:13.283 Detail,
2020-10-01 23:59:13.283 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:13.284 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:13.284 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:13.284 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:13.284 Info, Timer: adding event in 250 ms
2020-10-01 23:59:13.284 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:13.284 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:13.284 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:13.284 Detail, Node030,   Message transaction complete
2020-10-01 23:59:13.284 Detail,
2020-10-01 23:59:13.284 Detail, Node030, Removing current message
2020-10-01 23:59:13.284 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-01 23:59:13.287 Detail, Timer: waiting with timeout 246 ms
2020-10-02T03:59:13.288Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:13.534 Info, Timer: delayed event
2020-10-01 23:59:13.534 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:13.534 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xcf, 0x34
2020-10-01 23:59:13.534 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:13.534 Detail,
2020-10-01 23:59:13.534 Info, Node030, Sending (Send) message (Callback ID=0xcf, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xcf, 0x34
2020-10-01 23:59:13.534 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:13.542 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:13.542 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:13.563 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xcf, 0x00, 0x00, 0x02, 0x26
2020-10-01 23:59:13.563 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xcf received (expected 0xcf)
2020-10-01 23:59:13.564 Info, Node030, Request RTT 29 Average Request RTT 28
2020-10-01 23:59:13.565 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:13.577 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:13.577 Detail,
2020-10-01 23:59:13.577 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:13.577 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:13.577 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:13.578 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:13.578 Info, Timer: adding event in 250 ms
2020-10-01 23:59:13.578 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:13.578 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:13.578 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:13.579 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:13.579 Detail, Node030,   Message transaction complete
2020-10-01 23:59:13.579 Detail,
2020-10-01 23:59:13.579 Detail, Node030, Removing current message
2020-10-01 23:59:13.580 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:13.580Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:13.827 Info, Timer: delayed event
2020-10-01 23:59:13.828 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:13.829 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd0, 0x2b
2020-10-01 23:59:13.829 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:13.830 Detail,
2020-10-01 23:59:13.831 Info, Node030, Sending (Send) message (Callback ID=0xd0, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd0, 0x2b
2020-10-01 23:59:13.832 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:13.840 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:13.841 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:13.861 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xd0, 0x00, 0x00, 0x02, 0x39
2020-10-01 23:59:13.862 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xd0 received (expected 0xd0)
2020-10-01 23:59:13.862 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:13.862 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:13.876 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:13.876 Detail,
2020-10-01 23:59:13.876 Info, Node030, Response RTT 42 Average Response RTT 42
2020-10-01 23:59:13.876 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:13.876 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:13.877 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:13.877 Info, Timer: adding event in 250 ms
2020-10-01 23:59:13.878 Detail, Timer: waiting with timeout 250 ms
2020-10-01 23:59:13.878 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:13.878 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:13.878 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:13.878 Detail, Node030,   Message transaction complete
2020-10-01 23:59:13.879 Detail,
2020-10-01 23:59:13.879 Detail, Node030, Removing current message
2020-10-01 23:59:13.880 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:13.880Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:14.128 Info, Timer: delayed event
2020-10-01 23:59:14.128 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:14.128 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd1, 0x2a
2020-10-01 23:59:14.128 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:14.128 Detail,
2020-10-01 23:59:14.128 Info, Node030, Sending (Send) message (Callback ID=0xd1, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd1, 0x2a
2020-10-01 23:59:14.128 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:14.135 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:14.135 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:14.156 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xd1, 0x00, 0x00, 0x03, 0x39
2020-10-01 23:59:14.156 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xd1 received (expected 0xd1)
2020-10-01 23:59:14.156 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:14.156 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:14.171 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:14.171 Detail,
2020-10-01 23:59:14.171 Info, Node030, Response RTT 42 Average Response RTT 42
2020-10-01 23:59:14.171 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:14.171 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:14.171 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:14.171 Info, Timer: adding event in 250 ms
2020-10-01 23:59:14.171 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:14.171 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:14.171 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:14.171 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:14.171 Detail, Node030,   Message transaction complete
2020-10-01 23:59:14.171 Detail,
2020-10-01 23:59:14.171 Detail, Node030, Removing current message
2020-10-01 23:59:14.171 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:14.172Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:14.420 Info, Timer: delayed event
2020-10-01 23:59:14.421 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:14.422 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd2, 0x29
2020-10-01 23:59:14.422 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:14.423 Detail,
2020-10-01 23:59:14.424 Info, Node030, Sending (Send) message (Callback ID=0xd2, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd2, 0x29
2020-10-01 23:59:14.425 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:14.433 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:14.433 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:14.454 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xd2, 0x00, 0x00, 0x02, 0x3b
2020-10-01 23:59:14.454 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xd2 received (expected 0xd2)
2020-10-01 23:59:14.454 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:14.454 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:14.468 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:14.469 Detail,
2020-10-01 23:59:14.469 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:14.470 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:14.470 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:14.471 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:14.471 Info, Timer: adding event in 250 ms
2020-10-01 23:59:14.472 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:14.473 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:14.473 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:14.474 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:14.474 Detail, Node030,   Message transaction complete
2020-10-01 23:59:14.474 Detail,
2020-10-01 23:59:14.474 Detail, Node030, Removing current message
2020-10-01 23:59:14.475 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:14.475Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:14.722 Info, Timer: delayed event
2020-10-01 23:59:14.723 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:14.723 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd3, 0x28
2020-10-01 23:59:14.723 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:14.725 Detail,
2020-10-01 23:59:14.725 Info, Node030, Sending (Send) message (Callback ID=0xd3, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd3, 0x28
2020-10-01 23:59:14.725 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:14.732 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:14.733 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:14.753 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xd3, 0x00, 0x00, 0x02, 0x3a
2020-10-01 23:59:14.753 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xd3 received (expected 0xd3)
2020-10-01 23:59:14.753 Info, Node030, Request RTT 28 Average Request RTT 28
2020-10-01 23:59:14.753 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:14.767 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:14.768 Detail,
2020-10-01 23:59:14.768 Info, Node030, Response RTT 43 Average Response RTT 42
2020-10-01 23:59:14.768 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:14.769 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:14.769 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:14.769 Info, Timer: adding event in 250 ms
2020-10-01 23:59:14.769 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:14.770 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:14.770 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:14.770 Detail, Node030,   Message transaction complete
2020-10-01 23:59:14.770 Detail,
2020-10-01 23:59:14.771 Detail, Node030, Removing current message
2020-10-01 23:59:14.771 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-01 23:59:14.771 Detail, Timer: waiting with timeout 250 ms
2020-10-02T03:59:14.772Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:15.021 Info, Timer: delayed event
2020-10-01 23:59:15.022 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:15.022 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd4, 0x2f
2020-10-01 23:59:15.022 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:15.023 Detail,
2020-10-01 23:59:15.023 Info, Node030, Sending (Send) message (Callback ID=0xd4, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd4, 0x2f
2020-10-01 23:59:15.023 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:15.032 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:15.032 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:15.053 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xd4, 0x00, 0x00, 0x02, 0x3d
2020-10-01 23:59:15.053 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xd4 received (expected 0xd4)
2020-10-01 23:59:15.054 Info, Node030, Request RTT 29 Average Request RTT 28
2020-10-01 23:59:15.054 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:15.067 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:15.068 Detail,
2020-10-01 23:59:15.069 Info, Node030, Response RTT 44 Average Response RTT 43
2020-10-01 23:59:15.069 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:15.069 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:15.069 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:15.069 Info, Timer: adding event in 250 ms
2020-10-01 23:59:15.070 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:15.070 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:15.070 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:15.070 Detail, Node030,   Message transaction complete
2020-10-01 23:59:15.070 Detail,
2020-10-01 23:59:15.071 Detail, Node030, Removing current message
2020-10-01 23:59:15.071 Detail, Timer: waiting with timeout 249 ms
2020-10-01 23:59:15.071 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:15.073Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-01 23:59:15.321 Info, Timer: delayed event
2020-10-01 23:59:15.321 Info, Node030, Sending Get to Refresh Value after Target Check
2020-10-01 23:59:15.321 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd5, 0x2e
2020-10-01 23:59:15.322 Detail,
2020-10-01 23:59:15.322 Info, Node030, Sending (Send) message (Callback ID=0xd5, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd5, 0x2e
2020-10-01 23:59:15.322 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:15.322 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:15.329 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:15.330 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:15.350 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xd5, 0x00, 0x00, 0x03, 0x3d
2020-10-01 23:59:15.351 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xd5 received (expected 0xd5)
2020-10-01 23:59:15.352 Info, Node030, Request RTT 30 Average Request RTT 29
2020-10-01 23:59:15.353 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:15.365 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0x00, 0xc9
2020-10-01 23:59:15.365 Detail,
2020-10-01 23:59:15.365 Info, Node030, Response RTT 42 Average Response RTT 42
2020-10-01 23:59:15.365 Info, Node030, Received SwitchBinary report from node 30: level=Off
2020-10-01 23:59:15.365 Detail, Node030, Value Updated: old value=true, new value=false, type=bool
2020-10-01 23:59:15.365 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:15.365 Info, Timer: adding event in 250 ms
2020-10-01 23:59:15.365 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:15.365 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:15.365 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:15.365 Detail, Node030,   Message transaction complete
2020-10-01 23:59:15.365 Detail,
2020-10-01 23:59:15.365 Detail, Node030, Removing current message
2020-10-01 23:59:15.366 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-01 23:59:15.366 Detail, Timer: waiting with timeout 249 ms
2020-10-02T03:59:15.368Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true
2020-10-02T03:59:15.540Z z2m:App Zwave api call: setValue [ 30, 37, 1, 0, true ]
2020-10-01 23:59:15.541 Info, Node030, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - True
2020-10-01 23:59:15.541 Info, Node030, SwitchBinary::Set - Setting to On
2020-10-01 23:59:15.542 Info, Node030,   Duration: Device Default
2020-10-01 23:59:15.542 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Set (Node=30): 0x01, 0x0b, 0x00, 0x13, 0x1e, 0x04, 0x25, 0x01, 0xff, 0xff, 0x25, 0xd6, 0x2a
2020-10-01 23:59:15.543 Detail, Node030, Queuing (Send) SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd7, 0x2c
2020-10-01 23:59:15.544 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-01 23:59:15.544 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-01 23:59:15.544 Warning, Cant Find TimerEvent to Delete in TimerDelEvent
2020-10-01 23:59:15.544 Detail,
2020-10-01 23:59:15.544 Info, Node030, Sending (Send) message (Callback ID=0xd6, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=30): 0x01, 0x0b, 0x00, 0x13, 0x1e, 0x04, 0x25, 0x01, 0xff, 0xff, 0x25, 0xd6, 0x2a
2020-10-01 23:59:15.544 Info, Node030, Encrypted Flag is 0
2020-10-02T03:59:15.544Z z2m:Zwave Success zwave api call setValue
2020-10-01 23:59:15.552 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:15.552 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:15.573 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xd6, 0x00, 0x00, 0x02, 0x3f
2020-10-01 23:59:15.573 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xd6 received (expected 0xd6)
2020-10-01 23:59:15.573 Info, Node030, Request RTT 29 Average Request RTT 29
2020-10-01 23:59:15.574 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:15.574 Detail, Node030,   Expected reply was received
2020-10-01 23:59:15.574 Detail, Node030,   Message transaction complete
2020-10-01 23:59:15.574 Detail,
2020-10-01 23:59:15.575 Detail, Node030, Removing current message
2020-10-01 23:59:15.575 Detail,
2020-10-01 23:59:15.575 Info, Node030, Sending (Send) message (Callback ID=0xd7, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=30): 0x01, 0x09, 0x00, 0x13, 0x1e, 0x02, 0x25, 0x02, 0x25, 0xd7, 0x2c
2020-10-01 23:59:15.575 Info, Node030, Encrypted Flag is 0
2020-10-01 23:59:15.583 Detail, Node030,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-10-01 23:59:15.583 Detail, Node030,   ZW_SEND_DATA delivered to Z-Wave stack
2020-10-01 23:59:15.604 Detail, Node030,   Received: 0x01, 0x07, 0x00, 0x13, 0xd7, 0x00, 0x00, 0x02, 0x3e
2020-10-01 23:59:15.605 Detail, Node030,   ZW_SEND_DATA Request with callback ID 0xd7 received (expected 0xd7)
2020-10-01 23:59:15.605 Info, Node030, Request RTT 29 Average Request RTT 29
2020-10-01 23:59:15.605 Detail, Node030,   Expected callbackId was received
2020-10-01 23:59:15.615 Detail, Timer: waiting with timeout -1 ms
2020-10-01 23:59:15.618 Detail, Node030,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x1e, 0x03, 0x25, 0x03, 0xff, 0x36
2020-10-01 23:59:15.619 Detail,
2020-10-01 23:59:15.620 Info, Node030, Response RTT 44 Average Response RTT 43
2020-10-01 23:59:15.621 Info, Node030, Received SwitchBinary report from node 30: level=On
2020-10-01 23:59:15.622 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:15.622 Detail, Node030,    Target Value is Set to true
2020-10-01 23:59:15.623 Detail, Node030, Value Updated: old value=true, new value=true, type=bool
2020-10-01 23:59:15.623 Detail, Node030, Changes to this value are not verified
2020-10-01 23:59:15.624 Detail, Node030,   Expected reply and command class was received
2020-10-01 23:59:15.624 Detail, Node030,   Message transaction complete
2020-10-01 23:59:15.624 Detail,
2020-10-01 23:59:15.624 Detail, Node030, Removing current message
2020-10-01 23:59:15.625 Detail, Node030, Notification: ValueChanged CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 0
2020-10-01 23:59:15.625 Detail, Node030, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 1
2020-10-02T03:59:15.625Z z2m:Zwave zwave node 30: changed: 37-1-0:Switch:true -> true
2020-10-02T03:59:15.626Z z2m:Zwave zwave node 30: changed: 37-1-1:Target State:true -> true

[robertsLando]

@hyperbolic2346 I really would like to help here but unfortunally that's out of my knowladge, I really have no idea about what's going on there what I could tell you is the things I told in prev comments like try to check for device firmware updates, refresh device node information, check the poll time and so on, also try to update z2m to latest version (ozw has been updated).

Only @Fishwaldo could help with this

[hyperbolic2346]

@robertsLando I understand and I appreciate your help thus far. I'm not ready to call this an issue with ozw, but it is possible. I'm not sure what is going on yet and I'm using this issue to document my findings as it progresses.

[mgw854]

I've run into the same issue trying to get my Leviton switches (DZ15S-1BZ) working with zwave2mqtt. It appears to be a bug in the Leviton implementation that open-zwave does not handle well. There's an open PR to fix this.

https://github.com/OpenZWave/open-zwave/pull/2370

[hyperbolic2346]

I have those switches as well and this looks to be exactly my issue. This is great news, thank you @mgw854.

[robertsLando]

Glad there is a fix for this, keep me updated

[billimek]

I'm experiencing the same issue with a different model Leviton zwave switch (DZPA1).

When toggling the power on->off, the zwave controller starts dumping similar logs as above.

In addition,

• Even with zwave logging disabled, the following gets logged repeatedly without end in the zwave2mqtt docker container - it's about 5 entries/second
• Even with zwave logging disabled, the overall zwave controller performance starts to degrade such that the other devices stop responding or respond very slowly.
• The last known working version, as far as I know, was the openzwave 1.6.0 release. Reverting to this (vis the robertslando/zwave2mqtt image tag 3.4.0) is the only remedy that I could find.

[robertsLando]

@billimek Are you sure you haven't enabled logging for that value? I mean check on Settings > Gateway > Values Table if you have that value in that table and the logging is enable that is the problem

[billimek]

@billimek Are you sure you haven't enabled logging for that value? I mean check on Settings > Gateway > Values Table if you have that value in that table and the logging is enable that is the problem

@robertsLando this is the setting?

Or I think you mean this table?

[robertsLando]

@billimek Nope I mean the table. Colud you please try to add that value to the table and __NOT__ enabling the enable poll flag?

That should call disable poll of that value automatically and may fix the problem

Just press on NEW VALUE in the bottom right corner of the table

[l3arcf]

PR mentioned earlier (OpenZWave/open-zwave#2370) has been merged. I assume it will be trivial to include that in Zwave2Mqtt now.

[robertsLando]

@l3arcf just need to wait next release

[hyperbolic2346]

@robertsLando is that on a periodic schedule or as-needed?

[robertsLando]

It depends when ozw releases a new stable at old.openzwave.com/downloads. After that I can bump the version on dockerfile and create a new release

[robertsLando]

Just saw there is a new stable 1.6.1510, will update tomorrow

[robertsLando]

@hyperbolic2346 @l3arcf New version released, will be available on dockerhub in an hour or less :)

[pdrahcir]

@hyperbolic2346 is this resolved for you? I am having a very similar issue where I am getting log spam and I am using the latest version. I am using zooz zen27 switches BTW.

Snippet of log:

2020-12-15 03:32:52.247 Detail,
2020-12-15 03:32:52.247 Info, Node019, Sending (Send) message (Callback ID=0xe8, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xe8, 0x1d
2020-12-15 03:32:52.248 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:52.255 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:52.255 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:52.275 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xe8, 0x00, 0x00, 0x02, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xca
2020-12-15 03:32:52.276 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xe8 received (expected 0xe8)
2020-12-15 03:32:52.276 Info, Node019, Request RTT 28 Average Request RTT 27
2020-12-15 03:32:52.276 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:52.276 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:52.291 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:52.291 Detail,
2020-12-15 03:32:52.291 Info, Node019, Response RTT 43 Average Response RTT 42
2020-12-15 03:32:52.291 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:52.291 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:52.291 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:52.291 Info, Timer: adding event in 250 ms
2020-12-15 03:32:52.291 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:52.291 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.291 Detail, Timer: waiting with timeout 21 ms
2020-12-15 03:32:52.291 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:52.291 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.291 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:52.291 Detail, Node019, Message transaction complete
2020-12-15 03:32:52.291 Detail,
2020-12-15 03:32:52.292 Detail, Node019, Removing current message
2020-12-15 03:32:52.292 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:52.292 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:52.312 Info, Timer: delayed event
2020-12-15 03:32:52.312 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:52.313 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xe9, 0x1c
2020-12-15 03:32:52.313 Detail, Timer: waiting with timeout 228 ms
2020-12-15 03:32:52.313 Detail,
2020-12-15 03:32:52.313 Info, Node019, Sending (Send) message (Callback ID=0xe9, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xe9, 0x1c
2020-12-15 03:32:52.313 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:52.320 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:52.320 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:52.340 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xe9, 0x00, 0x00, 0x02, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xcb
2020-12-15 03:32:52.340 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xe9 received (expected 0xe9)
2020-12-15 03:32:52.340 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:52.341 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:52.341 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:52.356 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:52.356 Detail,
2020-12-15 03:32:52.356 Info, Node019, Response RTT 43 Average Response RTT 42
2020-12-15 03:32:52.356 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:52.356 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:52.356 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:52.356 Info, Timer: adding event in 250 ms
2020-12-15 03:32:52.356 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:52.356 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.356 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:52.356 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.356 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:52.356 Detail, Node019, Message transaction complete
2020-12-15 03:32:52.356 Detail,
2020-12-15 03:32:52.356 Detail, Node019, Removing current message
2020-12-15 03:32:52.356 Detail, Timer: waiting with timeout 185 ms
2020-12-15 03:32:52.356 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:52.357 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:52.542 Info, Timer: delayed event
2020-12-15 03:32:52.542 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:52.542 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xea, 0x1f
2020-12-15 03:32:52.542 Detail, Timer: waiting with timeout 64 ms
2020-12-15 03:32:52.542 Detail,
2020-12-15 03:32:52.542 Info, Node019, Sending (Send) message (Callback ID=0xea, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xea, 0x1f
2020-12-15 03:32:52.542 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:52.549 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:52.549 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:52.570 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xea, 0x00, 0x00, 0x02, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xc8
2020-12-15 03:32:52.570 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xea received (expected 0xea)
2020-12-15 03:32:52.570 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:52.570 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:52.570 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:52.585 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd7, 0x00, 0x13
2020-12-15 03:32:52.585 Detail,
2020-12-15 03:32:52.585 Info, Node019, Response RTT 42 Average Response RTT 42
2020-12-15 03:32:52.585 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:52.585 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:52.585 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:52.585 Info, Timer: adding event in 250 ms
2020-12-15 03:32:52.585 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:52.585 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.585 Detail, Timer: waiting with timeout 20 ms
2020-12-15 03:32:52.585 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:52.585 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.585 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:52.585 Detail, Node019, Message transaction complete
2020-12-15 03:32:52.585 Detail,
2020-12-15 03:32:52.586 Detail, Node019, Removing current message
2020-12-15 03:32:52.586 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:52.586 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:52.605 Info, Timer: delayed event
2020-12-15 03:32:52.605 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:52.606 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xeb, 0x1e
2020-12-15 03:32:52.606 Detail, Timer: waiting with timeout 229 ms
2020-12-15 03:32:52.606 Detail,
2020-12-15 03:32:52.606 Info, Node019, Sending (Send) message (Callback ID=0xeb, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xeb, 0x1e
2020-12-15 03:32:52.606 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:52.613 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:52.613 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:52.633 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xeb, 0x00, 0x00, 0x03, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xc8
2020-12-15 03:32:52.633 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xeb received (expected 0xeb)
2020-12-15 03:32:52.633 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:52.633 Detail, Node019, Extended TxStatus: Time: 30, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:52.633 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:52.649 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:52.649 Detail,
2020-12-15 03:32:52.649 Info, Node019, Response RTT 42 Average Response RTT 42
2020-12-15 03:32:52.649 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:52.649 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:52.649 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:52.649 Info, Timer: adding event in 250 ms
2020-12-15 03:32:52.649 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:52.649 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.649 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:52.649 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.649 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:52.649 Detail, Node019, Message transaction complete
2020-12-15 03:32:52.649 Detail,
2020-12-15 03:32:52.649 Detail, Node019, Removing current message
2020-12-15 03:32:52.649 Detail, Timer: waiting with timeout 185 ms
2020-12-15 03:32:52.650 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:52.650 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:52.835 Info, Timer: delayed event
2020-12-15 03:32:52.835 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:52.835 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xec, 0x19
2020-12-15 03:32:52.835 Detail, Timer: waiting with timeout 64 ms
2020-12-15 03:32:52.835 Detail,
2020-12-15 03:32:52.835 Info, Node019, Sending (Send) message (Callback ID=0xec, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xec, 0x19
2020-12-15 03:32:52.835 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:52.842 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:52.843 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:52.863 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xec, 0x00, 0x00, 0x02, 0x00, 0xd7, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xcc
2020-12-15 03:32:52.863 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xec received (expected 0xec)
2020-12-15 03:32:52.863 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:52.863 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -41 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:52.863 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:52.878 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:52.878 Detail,
2020-12-15 03:32:52.878 Info, Node019, Response RTT 43 Average Response RTT 42
2020-12-15 03:32:52.879 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:52.879 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:52.879 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:52.879 Info, Timer: adding event in 250 ms
2020-12-15 03:32:52.879 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:52.879 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.879 Detail, Timer: waiting with timeout 20 ms
2020-12-15 03:32:52.879 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:52.879 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.879 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:52.879 Detail, Node019, Message transaction complete
2020-12-15 03:32:52.879 Detail,
2020-12-15 03:32:52.879 Detail, Node019, Removing current message
2020-12-15 03:32:52.879 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:52.879 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:52.899 Info, Timer: delayed event
2020-12-15 03:32:52.899 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:52.899 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xed, 0x18
2020-12-15 03:32:52.899 Detail, Timer: waiting with timeout 230 ms
2020-12-15 03:32:52.899 Detail,
2020-12-15 03:32:52.899 Info, Node019, Sending (Send) message (Callback ID=0xed, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xed, 0x18
2020-12-15 03:32:52.899 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:52.906 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:52.906 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:52.926 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xed, 0x00, 0x00, 0x02, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xcf
2020-12-15 03:32:52.926 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xed received (expected 0xed)
2020-12-15 03:32:52.926 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:52.927 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:52.927 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:52.942 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:52.942 Detail,
2020-12-15 03:32:52.942 Info, Node019, Response RTT 43 Average Response RTT 42
2020-12-15 03:32:52.942 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:52.942 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:52.943 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:52.943 Info, Timer: adding event in 250 ms
2020-12-15 03:32:52.943 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:52.943 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.943 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:52.943 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:52.943 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:52.943 Detail, Node019, Message transaction complete
2020-12-15 03:32:52.943 Detail,
2020-12-15 03:32:52.943 Detail, Node019, Removing current message
2020-12-15 03:32:52.943 Detail, Timer: waiting with timeout 187 ms
2020-12-15 03:32:52.943 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:52.943 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:53.130 Info, Timer: delayed event
2020-12-15 03:32:53.130 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:53.130 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xee, 0x1b
2020-12-15 03:32:53.130 Detail, Timer: waiting with timeout 62 ms
2020-12-15 03:32:53.130 Detail,
2020-12-15 03:32:53.130 Info, Node019, Sending (Send) message (Callback ID=0xee, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xee, 0x1b
2020-12-15 03:32:53.130 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:53.138 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:53.138 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:53.158 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xee, 0x00, 0x00, 0x02, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xcc
2020-12-15 03:32:53.158 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xee received (expected 0xee)
2020-12-15 03:32:53.158 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:53.158 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:53.158 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:53.173 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd7, 0x00, 0x13
2020-12-15 03:32:53.173 Detail,
2020-12-15 03:32:53.173 Info, Node019, Response RTT 42 Average Response RTT 42
2020-12-15 03:32:53.173 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:53.173 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:53.173 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:53.173 Info, Timer: adding event in 250 ms
2020-12-15 03:32:53.174 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:53.174 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.174 Detail, Timer: waiting with timeout 19 ms
2020-12-15 03:32:53.174 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:53.174 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.174 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:53.174 Detail, Node019, Message transaction complete
2020-12-15 03:32:53.174 Detail,
2020-12-15 03:32:53.174 Detail, Node019, Removing current message
2020-12-15 03:32:53.174 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:53.174 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:53.193 Info, Timer: delayed event
2020-12-15 03:32:53.193 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:53.193 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xef, 0x1a
2020-12-15 03:32:53.193 Detail, Timer: waiting with timeout 230 ms
2020-12-15 03:32:53.193 Detail,
2020-12-15 03:32:53.193 Info, Node019, Sending (Send) message (Callback ID=0xef, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xef, 0x1a
2020-12-15 03:32:53.193 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:53.200 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:53.200 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:53.220 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xef, 0x00, 0x00, 0x02, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xcd
2020-12-15 03:32:53.220 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xef received (expected 0xef)
2020-12-15 03:32:53.221 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:53.221 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:53.221 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:53.236 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:53.236 Detail,
2020-12-15 03:32:53.236 Info, Node019, Response RTT 42 Average Response RTT 42
2020-12-15 03:32:53.236 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:53.236 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:53.236 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:53.236 Info, Timer: adding event in 250 ms
2020-12-15 03:32:53.236 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:53.236 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.236 Detail, Timer: waiting with timeout 187 ms
2020-12-15 03:32:53.236 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:53.237 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.237 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:53.237 Detail, Node019, Message transaction complete
2020-12-15 03:32:53.237 Detail,
2020-12-15 03:32:53.237 Detail, Node019, Removing current message
2020-12-15 03:32:53.237 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:53.237 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:53.424 Info, Timer: delayed event
2020-12-15 03:32:53.424 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:53.424 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf0, 0x05
2020-12-15 03:32:53.424 Detail, Timer: waiting with timeout 62 ms
2020-12-15 03:32:53.424 Detail,
2020-12-15 03:32:53.424 Info, Node019, Sending (Send) message (Callback ID=0xf0, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf0, 0x05
2020-12-15 03:32:53.424 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:53.431 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:53.431 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:53.451 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xf0, 0x00, 0x00, 0x02, 0x00, 0xd7, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xd0
2020-12-15 03:32:53.452 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xf0 received (expected 0xf0)
2020-12-15 03:32:53.452 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:53.452 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -41 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:53.452 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:53.467 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd7, 0x00, 0x13
2020-12-15 03:32:53.467 Detail,
2020-12-15 03:32:53.467 Info, Node019, Response RTT 43 Average Response RTT 42
2020-12-15 03:32:53.467 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:53.467 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:53.467 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:53.467 Info, Timer: adding event in 250 ms
2020-12-15 03:32:53.467 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:53.467 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.468 Detail, Timer: waiting with timeout 18 ms
2020-12-15 03:32:53.468 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:53.468 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.468 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:53.468 Detail, Node019, Message transaction complete
2020-12-15 03:32:53.468 Detail,
2020-12-15 03:32:53.468 Detail, Node019, Removing current message
2020-12-15 03:32:53.468 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:53.468 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:53.486 Info, Timer: delayed event
2020-12-15 03:32:53.486 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:53.486 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf1, 0x04
2020-12-15 03:32:53.486 Detail, Timer: waiting with timeout 231 ms
2020-12-15 03:32:53.486 Detail,
2020-12-15 03:32:53.486 Info, Node019, Sending (Send) message (Callback ID=0xf1, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf1, 0x04
2020-12-15 03:32:53.486 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:53.493 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:53.493 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:53.513 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xf1, 0x00, 0x00, 0x03, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xd2
2020-12-15 03:32:53.513 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xf1 received (expected 0xf1)
2020-12-15 03:32:53.513 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:53.513 Detail, Node019, Extended TxStatus: Time: 30, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:53.513 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:53.529 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:53.529 Detail,
2020-12-15 03:32:53.529 Info, Node019, Response RTT 42 Average Response RTT 42
2020-12-15 03:32:53.529 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:53.529 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:53.529 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:53.529 Info, Timer: adding event in 250 ms
2020-12-15 03:32:53.529 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:53.529 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.529 Detail, Timer: waiting with timeout 188 ms
2020-12-15 03:32:53.529 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:53.529 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.529 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:53.529 Detail, Node019, Message transaction complete
2020-12-15 03:32:53.529 Detail,
2020-12-15 03:32:53.529 Detail, Node019, Removing current message
2020-12-15 03:32:53.529 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:53.529 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:53.717 Info, Timer: delayed event
2020-12-15 03:32:53.717 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:53.717 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf2, 0x07
2020-12-15 03:32:53.717 Detail, Timer: waiting with timeout 61 ms
2020-12-15 03:32:53.718 Detail,
2020-12-15 03:32:53.718 Info, Node019, Sending (Send) message (Callback ID=0xf2, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf2, 0x07
2020-12-15 03:32:53.718 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:53.725 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:53.725 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:53.745 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xf2, 0x00, 0x00, 0x02, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xd0
2020-12-15 03:32:53.745 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xf2 received (expected 0xf2)
2020-12-15 03:32:53.745 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:53.746 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:53.746 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:53.761 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:53.761 Detail,
2020-12-15 03:32:53.761 Info, Node019, Response RTT 43 Average Response RTT 42
2020-12-15 03:32:53.761 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:53.761 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:53.761 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:53.761 Info, Timer: adding event in 250 ms
2020-12-15 03:32:53.761 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:53.761 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.761 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:53.761 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.761 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:53.761 Detail, Node019, Message transaction complete
2020-12-15 03:32:53.761 Detail,
2020-12-15 03:32:53.761 Detail, Node019, Removing current message
2020-12-15 03:32:53.762 Detail, Timer: waiting with timeout 17 ms
2020-12-15 03:32:53.762 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:53.762 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:53.779 Info, Timer: delayed event
2020-12-15 03:32:53.779 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:53.779 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf3, 0x06
2020-12-15 03:32:53.779 Detail, Timer: waiting with timeout 233 ms
2020-12-15 03:32:53.779 Detail,
2020-12-15 03:32:53.779 Info, Node019, Sending (Send) message (Callback ID=0xf3, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf3, 0x06
2020-12-15 03:32:53.779 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:53.786 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:53.786 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:53.806 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xf3, 0x00, 0x00, 0x02, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xd1
2020-12-15 03:32:53.806 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xf3 received (expected 0xf3)
2020-12-15 03:32:53.807 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:53.807 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:53.807 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:53.822 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd7, 0x00, 0x13
2020-12-15 03:32:53.822 Detail,
2020-12-15 03:32:53.822 Info, Node019, Response RTT 43 Average Response RTT 42
2020-12-15 03:32:53.822 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:53.822 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:53.822 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:53.822 Info, Timer: adding event in 250 ms
2020-12-15 03:32:53.823 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:53.823 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.823 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:53.823 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:53.823 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:53.823 Detail, Node019, Message transaction complete
2020-12-15 03:32:53.823 Detail,
2020-12-15 03:32:53.823 Detail, Node019, Removing current message
2020-12-15 03:32:53.823 Detail, Timer: waiting with timeout 189 ms
2020-12-15 03:32:53.823 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:53.823 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:54.012 Info, Timer: delayed event
2020-12-15 03:32:54.012 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:54.012 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf4, 0x01
2020-12-15 03:32:54.012 Detail, Timer: waiting with timeout 60 ms
2020-12-15 03:32:54.012 Detail,
2020-12-15 03:32:54.012 Info, Node019, Sending (Send) message (Callback ID=0xf4, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf4, 0x01
2020-12-15 03:32:54.012 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:54.020 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:54.020 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:54.040 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xf4, 0x00, 0x00, 0x02, 0x00, 0xd5, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xd6
2020-12-15 03:32:54.040 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xf4 received (expected 0xf4)
2020-12-15 03:32:54.040 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:54.040 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -43 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:54.040 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:54.056 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:54.056 Detail,
2020-12-15 03:32:54.056 Info, Node019, Response RTT 43 Average Response RTT 42
2020-12-15 03:32:54.056 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:54.056 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:54.056 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:54.056 Info, Timer: adding event in 250 ms
2020-12-15 03:32:54.056 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:54.056 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:54.056 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:54.056 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:54.056 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:54.056 Detail, Node019, Message transaction complete
2020-12-15 03:32:54.056 Detail,
2020-12-15 03:32:54.056 Detail, Node019, Removing current message
2020-12-15 03:32:54.056 Detail, Timer: waiting with timeout 16 ms
2020-12-15 03:32:54.056 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 9
2020-12-15 03:32:54.056 Detail, Node019, Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 5
2020-12-15 03:32:54.072 Info, Timer: delayed event
2020-12-15 03:32:54.073 Info, Node019, Sending Get to Refresh Value after Target Check
2020-12-15 03:32:54.073 Detail, Node019, Queuing (Send) SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf5, 0x00
2020-12-15 03:32:54.073 Detail, Timer: waiting with timeout 233 ms
2020-12-15 03:32:54.073 Detail,
2020-12-15 03:32:54.073 Info, Node019, Sending (Send) message (Callback ID=0xf5, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=19): 0x01, 0x09, 0x00, 0x13, 0x13, 0x02, 0x26, 0x02, 0x25, 0xf5, 0x00
2020-12-15 03:32:54.073 Info, Node019, Encrypted Flag is 0
2020-12-15 03:32:54.080 Detail, Node019, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-15 03:32:54.080 Detail, Node019, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-15 03:32:54.100 Detail, Node019, Received: 0x01, 0x18, 0x00, 0x13, 0xf5, 0x00, 0x00, 0x02, 0x00, 0xd7, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0xd5
2020-12-15 03:32:54.101 Detail, Node019, ZW_SEND_DATA Request with callback ID 0xf5 received (expected 0xf5)
2020-12-15 03:32:54.101 Info, Node019, Request RTT 27 Average Request RTT 27
2020-12-15 03:32:54.101 Detail, Node019, Extended TxStatus: Time: 20, Hops: 0, Rssi: -41 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-12-15 03:32:54.101 Detail, Node019, Expected callbackId was received
2020-12-15 03:32:54.116 Detail, Node019, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x13, 0x05, 0x26, 0x03, 0x63, 0x62, 0x00, 0xd5, 0x00, 0x11
2020-12-15 03:32:54.116 Detail,
2020-12-15 03:32:54.116 Info, Node019, Response RTT 43 Average Response RTT 42
2020-12-15 03:32:54.116 Info, Node019, Received SwitchMultiLevel report: level=99
2020-12-15 03:32:54.116 Detail, Node019, Value Updated: old value=3, new value=99, type=byte
2020-12-15 03:32:54.116 Detail, Node019, Target Value is Set to 98
2020-12-15 03:32:54.116 Info, Timer: adding event in 250 ms
2020-12-15 03:32:54.116 Detail, Node019, Value Updated: old value=98, new value=98, type=byte
2020-12-15 03:32:54.116 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:54.116 Detail, Node019, Value Updated: old value=0, new value=0, type=int
2020-12-15 03:32:54.116 Detail, Node019, Changes to this value are not verified
2020-12-15 03:32:54.116 Detail, Node019, Expected reply and command class was received
2020-12-15 03:32:54.117 Detail, Node019, Message transaction complete
`

[hyperbolic2346]

@pdrahcir I haven’t seen the issue since this fix went in to a release. I notice you have a different device. I wonder if it is doing something similar but not the same. ☹️ I would suggest a new issue to track it.

[robertsLando]

Hi guys, I'm working on a completely new version of this project here: https://github.com/zwave-js/zwavejs2mqtt#why-zwavejs

Take a look at it :)

[pdrahcir]

Thanks for your help.

@robertsLando I have installed your new version and I do not see the issue anymore. I will proceed with it. Thanks!

[hyperbolic2346]

@robertsLando What is the stability of that project? Are you suggesting migration for zwave2mqtt users?

[robertsLando]

@hyperbolic2346 Actually it's on alpha version, what I suggest is to give it a try, it works better then this version for most users right now :) If you have some device comapatibility problem just open an issue and we will fix them.