search

OpenZWave / Zwave2Mqtt

Fully configurable Zwave to MQTT gateway and Control Panel using NodeJS and Vue
MIT License
356 stars 92 forks source link

[question] Stale response to switch state change command #863

Open stuartwishart opened 3 years ago

stuartwishart commented 3 years ago

I'm regularly getting into a situation when using Z2M with HA (both installed in Docks) where switch state change commands are get a response from the Zwave device stating that the state change has not occurred. However, I can see that the state change command has been actioned by the switch. If I restart the Z2M docker container the problem goes away for a short period and then reoccurs.

Q: Is this a hardware issue or a SW bug?

Hardware

Version Zwave2Mqtt Docker image (openzwave/ozwdaemon:allinone-latest)

Good

2020-12-31 00:36:34.967118184 [20201231 11:36:34.966 AEDT] [ozw.mqtt.commands] [debug]: Got "OpenZWave/1/command/setvalue/" Message: "{\"ValueIDKey\": 155795472, \"Value\": true}" 2020-12-31 00:36:34.967851454 [20201231 11:36:34.967 AEDT] [ozw.mqtt.commands.setValue] [info]: Setting 155795472 to Value QVariant(bool, true) 2020-12-31 00:36:34.968438177 [20201231 11:36:34.968 AEDT] [ozw.values] [debug]: setData Called for Row 201 With Value QVariant(bool, true) 2020-12-31 00:36:34.968734345 [20201231 11:36:34.968 AEDT] [ozw.values] [debug]: valueModel Changed! QTOZW_ValueIds::Value - 201 : QVariant(bool, true) 2020-12-31 00:36:34.968928103 [20201231 11:36:34.968 AEDT] [ozw.values] [debug]: valueModel Changed! vidKey: 155795472 QVariant(bool, true) 2020-12-31 00:36:34.969092025 [20201231 11:36:34.969 AEDT] [ozw.library] [info]: Info - Node: 9 Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - True 2020-12-31 00:36:34.969264995 [20201231 11:36:34.969 AEDT] [ozw.library] [info]: Info - Node: 9 SwitchBinary::Set - Setting to On 2020-12-31 00:36:34.969442791 [20201231 11:36:34.969 AEDT] [ozw.library] [debug]: Detail - Node: 9 Queuing (Send) SwitchBinaryCmd_Set (Node=9): 0x01, 0x0a, 0x00, 0x13, 0x09, 0x03, 0x25, 0x01, 0xff, 0x25, 0xe2, 0xf0 2020-12-31 00:36:34.969733697 [20201231 11:36:34.969 AEDT] [ozw.library] [debug]: Detail - Node: 9 Queuing (Send) SwitchBinaryCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x25, 0x02, 0x25, 0xe3, 0x0f 2020-12-31 00:36:34.970220098 [20201231 11:36:34.969 AEDT] [ozw.library] [info]: Info - Node: 9 Sending (Send) message (Callback ID=0xe2, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=9): 0x01, 0x0a, 0x00, 0x13, 0x09, 0x03, 0x25, 0x01, 0xff, 0x25, 0xe2, 0xf0 2020-12-31 00:36:34.970228298 [20201231 11:36:34.970 AEDT] [ozw.library] [info]: Info - Node: 9 Encrypted Flag is 0 2020-12-31 00:36:34.970554227 [20201231 11:36:34.970 AEDT] [ozw.mqtt.commands] [info]: Processed Message for "setvalue" : "{\"ValueIDKey\": 155795472, \"Value\": true}" 2020-12-31 00:36:34.978087025 [20201231 11:36:34.977 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2020-12-31 00:36:34.978295632 [20201231 11:36:34.977 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA delivered to Z-Wave stack 2020-12-31 00:36:34.998941371 [20201231 11:36:34.998 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x07, 0x00, 0x13, 0xe2, 0x00, 0x00, 0x03, 0x0a 2020-12-31 00:36:34.999417960 [20201231 11:36:34.999 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA Request with callback ID 0xe2 received (expected 0xe2) 2020-12-31 00:36:34.999577869 [20201231 11:36:34.999 AEDT] [ozw.library] [info]: Info - Node: 9 Request RTT 29 Average Request RTT 29 2020-12-31 00:36:34.999792164 [20201231 11:36:34.999 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected callbackId was received 2020-12-31 00:36:34.999942335 [20201231 11:36:34.999 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected reply was received 2020-12-31 00:36:35.000093181 [20201231 11:36:34.999 AEDT] [ozw.library] [debug]: Detail - Node: 9 Message transaction complete 2020-12-31 00:36:35.000268990 [20201231 11:36:35.000 AEDT] [ozw.library] [debug]: Detail - Node: 9 Removing current message 2020-12-31 00:36:35.000490585 [20201231 11:36:35.000 AEDT] [ozw.library] [info]: Info - Node: 9 Sending (Send) message (Callback ID=0xe3, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x25, 0x02, 0x25, 0xe3, 0x0f 2020-12-31 00:36:35.000644506 [20201231 11:36:35.000 AEDT] [ozw.library] [info]: Info - Node: 9 Encrypted Flag is 0 2020-12-31 00:36:35.008420373 [20201231 11:36:35.008 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2020-12-31 00:36:35.008664367 [20201231 11:36:35.008 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA delivered to Z-Wave stack 2020-12-31 00:36:35.029889330 [20201231 11:36:35.029 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x07, 0x00, 0x13, 0xe3, 0x00, 0x00, 0x03, 0x0b 2020-12-31 00:36:35.030063288 [20201231 11:36:35.029 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA Request with callback ID 0xe3 received (expected 0xe3) 2020-12-31 00:36:35.030199272 [20201231 11:36:35.030 AEDT] [ozw.library] [info]: Info - Node: 9 Request RTT 29 Average Request RTT 29 2020-12-31 00:36:35.030247371 [20201231 11:36:35.030 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected callbackId was received 2020-12-31 00:36:35.053598908 [20201231 11:36:35.053 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x25, 0x03, 0xff, 0x21 2020-12-31 00:36:35.053935575 [20201231 11:36:35.053 AEDT] [ozw.library] [info]: Info - Node: 9 Response RTT 52 Average Response RTT 48 2020-12-31 00:36:35.054006049 [20201231 11:36:35.053 AEDT] [ozw.library] [info]: Info - Node: 9 Received SwitchBinary report from node 9: level=On 2020-12-31 00:36:35.054326579 [20201231 11:36:35.054 AEDT] [ozw.library] [debug]: Detail - Node: 9 Value Updated: old value=false, new value=true, type=bool 2020-12-31 00:36:35.054336691 [20201231 11:36:35.054 AEDT] [ozw.library] [debug]: Detail - Node: 9 Changes to this value are not verified 2020-12-31 00:36:35.054567748 [20201231 11:36:35.054 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected reply and command class was received 2020-12-31 00:36:35.054655858 [20201231 11:36:35.054 AEDT] [ozw.library] [debug]: Detail - Node: 9 Message transaction complete 2020-12-31 00:36:35.054868241 [20201231 11:36:35.054 AEDT] [ozw.library] [debug]: Detail - Node: 9 Removing current message 2020-12-31 00:36:35.055165234 [20201231 11:36:35.054 AEDT] [ozw.library] [debug]: Detail - Node: 9 Notification: ValueChanged CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 0 2020-12-31 00:36:35.055446402 [20201231 11:36:35.055 AEDT] [ozw.notifications] [debug]: Notification pvt_valueChanged: 155795472 Thread: 0x7fc5c62f59c0 2020-12-31 00:36:35.056351643 [20201231 11:36:35.056 AEDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 155795472

Bad1

2020-12-31 01:48:09.416508282 [20201231 12:48:09.416 AEDT] [ozw.mqtt.commands] [debug]: Got "OpenZWave/1/command/setvalue/" Message: "{\"ValueIDKey\": 155795472, \"Value\": true}" 2020-12-31 01:48:09.417172116 [20201231 12:48:09.417 AEDT] [ozw.mqtt.commands.setValue] [info]: Setting 155795472 to Value QVariant(bool, true) 2020-12-31 01:48:09.417492033 [20201231 12:48:09.417 AEDT] [ozw.values] [debug]: setData Called for Row 201 With Value QVariant(bool, true) 2020-12-31 01:48:09.417670454 [20201231 12:48:09.417 AEDT] [ozw.values] [debug]: valueModel Changed! QTOZW_ValueIds::Value - 201 : QVariant(bool, true) 2020-12-31 01:48:09.417818600 [20201231 12:48:09.417 AEDT] [ozw.values] [debug]: valueModel Changed! vidKey: 155795472 QVariant(bool, true) 2020-12-31 01:48:09.417990796 [20201231 12:48:09.417 AEDT] [ozw.library] [info]: Info - Node: 9 Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - True 2020-12-31 01:48:09.418151442 [20201231 12:48:09.418 AEDT] [ozw.library] [info]: Info - Node: 9 SwitchBinary::Set - Setting to On 2020-12-31 01:48:09.418332313 [20201231 12:48:09.418 AEDT] [ozw.library] [debug]: Detail - Node: 9 Queuing (Send) SwitchBinaryCmd_Set (Node=9): 0x01, 0x0a, 0x00, 0x13, 0x09, 0x03, 0x25, 0x01, 0xff, 0x25, 0xfa, 0xe8 2020-12-31 01:48:09.418512296 [20201231 12:48:09.418 AEDT] [ozw.library] [debug]: Detail - Node: 9 Queuing (Send) SwitchBinaryCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x25, 0x02, 0x25, 0xfb, 0x17 2020-12-31 01:48:09.418905149 [20201231 12:48:09.418 AEDT] [ozw.library] [info]: Info - Node: 9 Sending (Send) message (Callback ID=0xfa, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=9): 0x01, 0x0a, 0x00, 0x13, 0x09, 0x03, 0x25, 0x01, 0xff, 0x25, 0xfa, 0xe8 2020-12-31 01:48:09.418962810 [20201231 12:48:09.418 AEDT] [ozw.mqtt.commands] [info]: Processed Message for "setvalue" : "{\"ValueIDKey\": 155795472, \"Value\": true}" 2020-12-31 01:48:09.419002547 [20201231 12:48:09.418 AEDT] [ozw.library] [info]: Info - Node: 9 Encrypted Flag is 0 2020-12-31 01:48:09.426928241 [20201231 12:48:09.426 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2020-12-31 01:48:09.427077599 [20201231 12:48:09.426 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA delivered to Z-Wave stack 2020-12-31 01:48:09.448547800 [20201231 12:48:09.448 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x07, 0x00, 0x13, 0xfa, 0x00, 0x00, 0x03, 0x12 2020-12-31 01:48:09.448559550 [20201231 12:48:09.448 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA Request with callback ID 0xfa received (expected 0xfa) 2020-12-31 01:48:09.448564225 [20201231 12:48:09.448 AEDT] [ozw.library] [info]: Info - Node: 9 Request RTT 29 Average Request RTT 28 2020-12-31 01:48:09.448641148 [20201231 12:48:09.448 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected callbackId was received 2020-12-31 01:48:09.448682597 [20201231 12:48:09.448 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected reply was received 2020-12-31 01:48:09.448781945 [20201231 12:48:09.448 AEDT] [ozw.library] [debug]: Detail - Node: 9 Message transaction complete 2020-12-31 01:48:09.448870930 [20201231 12:48:09.448 AEDT] [ozw.library] [debug]: Detail - Node: 9 Removing current message 2020-12-31 01:48:09.449084025 [20201231 12:48:09.448 AEDT] [ozw.library] [info]: Info - Node: 9 Sending (Send) message (Callback ID=0xfb, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x25, 0x02, 0x25, 0xfb, 0x17 2020-12-31 01:48:09.449089812 [20201231 12:48:09.449 AEDT] [ozw.library] [info]: Info - Node: 9 Encrypted Flag is 0 2020-12-31 01:48:09.457204026 [20201231 12:48:09.456 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2020-12-31 01:48:09.457217364 [20201231 12:48:09.456 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA delivered to Z-Wave stack 2020-12-31 01:48:09.477475631 [20201231 12:48:09.477 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x07, 0x00, 0x13, 0xfb, 0x00, 0x00, 0x03, 0x13 2020-12-31 01:48:09.477587104 [20201231 12:48:09.477 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA Request with callback ID 0xfb received (expected 0xfb) 2020-12-31 01:48:09.477591854 [20201231 12:48:09.477 AEDT] [ozw.library] [info]: Info - Node: 9 Request RTT 28 Average Request RTT 28 2020-12-31 01:48:09.477623190 [20201231 12:48:09.477 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected callbackId was received 2020-12-31 01:48:09.525708554 [20201231 12:48:09.525 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x25, 0x03, 0x00, 0xde 2020-12-31 01:48:09.525859951 [20201231 12:48:09.525 AEDT] [ozw.library] [info]: Info - Node: 9 Response RTT 76 Average Response RTT 63 2020-12-31 01:48:09.525863976 [20201231 12:48:09.525 AEDT] [ozw.library] [info]: Info - Node: 9 Received SwitchBinary report from node 9: level=Off 2020-12-31 01:48:09.526094670 [20201231 12:48:09.525 AEDT] [ozw.library] [debug]: Detail - Node: 9 Value Updated: old value=false, new value=false, type=bool 2020-12-31 01:48:09.526099307 [20201231 12:48:09.525 AEDT] [ozw.library] [debug]: Detail - Node: 9 Changes to this value are not verified 2020-12-31 01:48:09.526149794 [20201231 12:48:09.526 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected reply and command class was received 2020-12-31 01:48:09.526234079 [20201231 12:48:09.526 AEDT] [ozw.library] [debug]: Detail - Node: 9 Message transaction complete 2020-12-31 01:48:09.526400100 [20201231 12:48:09.526 AEDT] [ozw.library] [debug]: Detail - Node: 9 Removing current message 2020-12-31 01:48:09.526555496 [20201231 12:48:09.526 AEDT] [ozw.library] [debug]: Detail - Node: 9 Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 0 2020-12-31 01:48:09.526811602 [20201231 12:48:09.526 AEDT] [ozw.notifications] [debug]: Notification pvt_valueRefreshed: 155795472 Thread: 0x7fc5c62f59c0 2020-12-31 01:48:09.527769029 [20201231 12:48:09.527 AEDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueRefreshed: 155795472

Bad2

2020-12-31 02:07:50.812113851 [20201231 13:07:50.811 AEDT] [ozw.mqtt.commands] [debug]: Got "OpenZWave/1/command/setvalue/" Message: "{\"ValueIDKey\": 155795472, \"Value\": false}" 2020-12-31 02:07:50.812709925 [20201231 13:07:50.812 AEDT] [ozw.mqtt.commands.setValue] [info]: Setting 155795472 to Value QVariant(bool, false) 2020-12-31 02:07:50.812772111 [20201231 13:07:50.812 AEDT] [ozw.values] [debug]: setData Called for Row 201 With Value QVariant(bool, false) 2020-12-31 02:07:50.812907882 [20201231 13:07:50.812 AEDT] [ozw.values] [debug]: valueModel Changed! QTOZW_ValueIds::Value - 201 : QVariant(bool, false) 2020-12-31 02:07:50.812965769 [20201231 13:07:50.812 AEDT] [ozw.values] [debug]: valueModel Changed! vidKey: 155795472 QVariant(bool, false) 2020-12-31 02:07:50.813249549 [20201231 13:07:50.813 AEDT] [ozw.library] [info]: Info - Node: 9 Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False 2020-12-31 02:07:50.813254462 [20201231 13:07:50.813 AEDT] [ozw.library] [info]: Info - Node: 9 SwitchBinary::Set - Setting to Off 2020-12-31 02:07:50.813309185 [20201231 13:07:50.813 AEDT] [ozw.library] [debug]: Detail - Node: 9 Queuing (Send) SwitchBinaryCmd_Set (Node=9): 0x01, 0x0a, 0x00, 0x13, 0x09, 0x03, 0x25, 0x01, 0x00, 0x25, 0x1d, 0xf0 2020-12-31 02:07:50.813644215 [20201231 13:07:50.813 AEDT] [ozw.library] [debug]: Detail - Node: 9 Queuing (Send) SwitchBinaryCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x25, 0x02, 0x25, 0x1e, 0xf2 2020-12-31 02:07:50.813721701 [20201231 13:07:50.813 AEDT] [ozw.mqtt.commands] [info]: Processed Message for "setvalue" : "{\"ValueIDKey\": 155795472, \"Value\": false}" 2020-12-31 02:07:50.814771238 [20201231 13:07:50.814 AEDT] [ozw.library] [info]: Info - Node: 9 Sending (Send) message (Callback ID=0x1d, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=9): 0x01, 0x0a, 0x00, 0x13, 0x09, 0x03, 0x25, 0x01, 0x00, 0x25, 0x1d, 0xf0 2020-12-31 02:07:50.814781938 [20201231 13:07:50.814 AEDT] [ozw.library] [info]: Info - Node: 9 Encrypted Flag is 0 2020-12-31 02:07:50.822566678 [20201231 13:07:50.822 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2020-12-31 02:07:50.822821335 [20201231 13:07:50.822 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA delivered to Z-Wave stack 2020-12-31 02:07:50.843426909 [20201231 13:07:50.843 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x07, 0x00, 0x13, 0x1d, 0x00, 0x00, 0x02, 0xf4 2020-12-31 02:07:50.843438796 [20201231 13:07:50.843 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA Request with callback ID 0x1d received (expected 0x1d) 2020-12-31 02:07:50.843442971 [20201231 13:07:50.843 AEDT] [ozw.library] [info]: Info - Node: 9 Request RTT 28 Average Request RTT 29 2020-12-31 02:07:50.843446346 [20201231 13:07:50.843 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected callbackId was received 2020-12-31 02:07:50.843449696 [20201231 13:07:50.843 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected reply was received 2020-12-31 02:07:50.843529681 [20201231 13:07:50.843 AEDT] [ozw.library] [debug]: Detail - Node: 9 Message transaction complete 2020-12-31 02:07:50.843625329 [20201231 13:07:50.843 AEDT] [ozw.library] [debug]: Detail - Node: 9 Removing current message 2020-12-31 02:07:50.843856636 [20201231 13:07:50.843 AEDT] [ozw.library] [info]: Info - Node: 9 Sending (Send) message (Callback ID=0x1e, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x25, 0x02, 0x25, 0x1e, 0xf2 2020-12-31 02:07:50.843901235 [20201231 13:07:50.843 AEDT] [ozw.library] [info]: Info - Node: 9 Encrypted Flag is 0 2020-12-31 02:07:50.852105516 [20201231 13:07:50.851 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 2020-12-31 02:07:50.852189001 [20201231 13:07:50.852 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA delivered to Z-Wave stack 2020-12-31 02:07:50.872150778 [20201231 13:07:50.871 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x07, 0x00, 0x13, 0x1e, 0x00, 0x00, 0x02, 0xf7 2020-12-31 02:07:50.872425659 [20201231 13:07:50.872 AEDT] [ozw.library] [debug]: Detail - Node: 9 ZW_SEND_DATA Request with callback ID 0x1e received (expected 0x1e) 2020-12-31 02:07:50.872520569 [20201231 13:07:50.872 AEDT] [ozw.library] [info]: Info - Node: 9 Request RTT 28 Average Request RTT 28 2020-12-31 02:07:50.872665866 [20201231 13:07:50.872 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected callbackId was received 2020-12-31 02:07:50.886010800 [20201231 13:07:50.885 AEDT] [ozw.library] [debug]: Detail - Node: 9 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 0x03, 0x25, 0x03, 0xff, 0x21 2020-12-31 02:07:50.886024024 [20201231 13:07:50.885 AEDT] [ozw.library] [info]: Info - Node: 9 Response RTT 41 Average Response RTT 43 2020-12-31 02:07:50.886029287 [20201231 13:07:50.885 AEDT] [ozw.library] [info]: Info - Node: 9 Received SwitchBinary report from node 9: level=On 2020-12-31 02:07:50.886127122 [20201231 13:07:50.886 AEDT] [ozw.library] [debug]: Detail - Node: 9 Value Updated: old value=true, new value=true, type=bool 2020-12-31 02:07:50.886132172 [20201231 13:07:50.886 AEDT] [ozw.library] [debug]: Detail - Node: 9 Changes to this value are not verified 2020-12-31 02:07:50.886974277 [20201231 13:07:50.886 AEDT] [ozw.library] [debug]: Detail - Node: 9 Expected reply and command class was received 2020-12-31 02:07:50.886983739 [20201231 13:07:50.886 AEDT] [ozw.library] [debug]: Detail - Node: 9 Message transaction complete 2020-12-31 02:07:50.886986939 [20201231 13:07:50.886 AEDT] [ozw.library] [debug]: Detail - Node: 9 Removing current message 2020-12-31 02:07:50.886989651 [20201231 13:07:50.886 AEDT] [ozw.library] [debug]: Detail - Node: 9 Notification: ValueRefreshed CC: COMMAND_CLASS_SWITCH_BINARY Instance: 1 Index: 0 2020-12-31 02:07:50.886992376 [20201231 13:07:50.886 AEDT] [ozw.notifications] [debug]: Notification pvt_valueRefreshed: 155795472 Thread: 0x7fc5c62f59c0 2020-12-31 02:07:50.887884030 [20201231 13:07:50.887 AEDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueRefreshed: 155795472

robertsLando commented 3 years ago

@stuartwishart did you try using zwavejs2mqtt?