search

home-assistant / addons

:heavy_plus_sign: Docker add-ons for Home Assistant
https://home-assistant.io/hassio/
Apache License 2.0
1.56k stars 1.51k forks source link

OZW crash when initializing nodes (?) #1536

Closed Dinth closed 4 years ago

Dinth commented 4 years ago

The problem

OZW beta addon has crashed during initialization

Environment

Traceback/Error logs

[20200811 21:02:33.449 BST] [ozw.notifications] [debug]: Notification pvt_valueRefreshed:  24206848393216020 Thread:  0x7f00b2759d48 
[20200811 21:02:33.452 BST] [ozw.mqtt.publisher.value] [debug]: List Selected Value has Not Changed:  "Enabled" 
[20200811 21:02:33.453 BST] [ozw.mqtt.publisher] [debug]: Publishing Event valueRefreshed: 24206848393216020 
[20200811 21:02:33.533 BST] [ozw.library] [debug]: Detail - Node: 0 Unsolicited message received while waiting for ACK. 
[20200811 21:02:33.533 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:33.543 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x17, 0x05, 0x70, 0x06, 0x56, 0x01, 0x01, 0xd2 
[20200811 21:02:33.543 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:33.544 BST] [ozw.library] [info]: Info - Node: 23 Response RTT 404 Average Response RTT 339 
[20200811 21:02:33.544 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:33.545 BST] [ozw.library] [debug]: Detail - Node: 23 Value Updated: old value=1, new value=1, type=list 
[20200811 21:02:33.545 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:33.545 BST] [ozw.library] [debug]: Detail - Node: 23 Changes to this value are not verified 
[20200811 21:02:33.545 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:33.546 BST] [ozw.library] [info]: Info - Node: 23 Received Configuration report: Parameter=86, Value=1 
[20200811 21:02:33.546 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:33.546 BST] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueRefreshed CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 86 
[20200811 21:02:33.546 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:33.547 BST] [ozw.notifications] [debug]: Notification pvt_valueRefreshed:  24206848393216020 Thread:  0x7f00b2759d48 
[20200811 21:02:33.549 BST] [ozw.mqtt.publisher.value] [debug]: List Selected Value has Not Changed:  "Enabled" 
[20200811 21:02:33.550 BST] [ozw.mqtt.publisher] [debug]: Publishing Event valueRefreshed: 24206848393216020 
[20200811 21:02:34.547 BST] [ozw.library] [info]: Info - Node: 23 Sending (Query) message (Attempt 2, Callback ID=0x36, Expected Reply=0x04) - ConfigurationCmd_Get (Node=23): 0x01, 0x0a, 0x00, 0x13, 0x17, 0x03, 0x70, 0x05, 0x6f, 0x25, 0x36, 0xfb 
[20200811 21:02:34.547 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:34.550 BST] [ozw.library] [info]: Info - Node: 23 Encrypted Flag is 0 
[20200811 21:02:34.550 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:34.564 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20200811 21:02:34.564 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:34.566 BST] [ozw.library] [debug]: Detail - Node: 23   ZW_SEND_DATA delivered to Z-Wave stack 
[20200811 21:02:34.566 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:35.416 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x07, 0x00, 0x13, 0x36, 0x00, 0x00, 0x56, 0x8b 
[20200811 21:02:35.416 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:35.417 BST] [ozw.library] [debug]: Detail - Node: 23   ZW_SEND_DATA Request with callback ID 0x36 received (expected 0x36) 
[20200811 21:02:35.417 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:35.418 BST] [ozw.library] [info]: Info - Node: 23 Request RTT 867 Average Request RTT 777 
[20200811 21:02:35.418 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:35.419 BST] [ozw.library] [debug]: Detail - Node: 23   Expected callbackId was received 
[20200811 21:02:35.419 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.494 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x17, 0x05, 0x70, 0x06, 0x57, 0x01, 0x03, 0xd1 
[20200811 21:02:36.495 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.496 BST] [ozw.library] [info]: Info - Node: 23 Response RTT 1945 Average Response RTT 1142 
[20200811 21:02:36.496 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.497 BST] [ozw.library] [debug]: Detail - Node: 23 Initial read of value 
[20200811 21:02:36.497 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.498 BST] [ozw.library] [info]: Info - Node: 23 Received Configuration report: Parameter=87, Value=3 
[20200811 21:02:36.498 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.499 BST] [ozw.library] [debug]: Detail - Node: 23   Expected reply and command class was received 
[20200811 21:02:36.499 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.499 BST] [ozw.library] [debug]: Detail - Node: 23   Message transaction complete 
[20200811 21:02:36.499 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.500 BST] [ozw.library] [debug]: Detail - Node: 23 Removing current message 
[20200811 21:02:36.500 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.501 BST] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueChanged CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 87 
[20200811 21:02:36.501 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.502 BST] [ozw.notifications] [debug]: Notification pvt_valueChanged:  24488323369926682 Thread:  0x7f00b2759d48 
[20200811 21:02:36.502 BST] [ozw.library] [info]: Info - Node: 23 Sending (Query) message (Callback ID=0x7e, Expected Reply=0x04) - ConfigurationCmd_Get (Node=23): 0x01, 0x0a, 0x00, 0x13, 0x17, 0x03, 0x70, 0x05, 0x87, 0x25, 0x7e, 0x5b 
[20200811 21:02:36.502 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.503 BST] [ozw.library] [info]: Info - Node: 23 Encrypted Flag is 0 
[20200811 21:02:36.503 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.508 BST] [ozw.mqtt.publisher.value] [warning]: Bitset is Missing Position or Value Members, or Incorrect Types:  "{\n    \"Label\": \"Sensor is triggered the buzzer will alarm\",\n    \"Value\": [],\n    \"Units\": \"\",\n    \"ValueSet\": false,\n    \"ValuePolled\": false,\n    \"ChangeVerified\": false,\n    \"Min\": 0,\n    \"Max\": 55,\n    \"Type\": \"BitSet\",\n    \"Instance\": 1,\n    \"CommandClass\": \"COMMAND_CLASS_CONFIGURATION\",\n    \"Index\": 87,\n    \"Node\": 23,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488323369926682,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1597173645\n}" 
[20200811 21:02:36.508 BST] [ozw.mqtt.publisher.value] [warning]: Bitset is Missing Position or Value Members, or Incorrect Types:  "{\n    \"Label\": \"Sensor is triggered the buzzer will alarm\",\n    \"Value\": [],\n    \"Units\": \"\",\n    \"ValueSet\": false,\n    \"ValuePolled\": false,\n    \"ChangeVerified\": false,\n    \"Min\": 0,\n    \"Max\": 55,\n    \"Type\": \"BitSet\",\n    \"Instance\": 1,\n    \"CommandClass\": \"COMMAND_CLASS_CONFIGURATION\",\n    \"Index\": 87,\n    \"Node\": 23,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488323369926682,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1597173645\n}" 
[20200811 21:02:36.508 BST] [ozw.mqtt.publisher.value] [warning]: Bitset is Missing Position or Value Members, or Incorrect Types:  "{\n    \"Label\": \"Sensor is triggered the buzzer will alarm\",\n    \"Value\": [],\n    \"Units\": \"\",\n    \"ValueSet\": false,\n    \"ValuePolled\": false,\n    \"ChangeVerified\": false,\n    \"Min\": 0,\n    \"Max\": 55,\n    \"Type\": \"BitSet\",\n    \"Instance\": 1,\n    \"CommandClass\": \"COMMAND_CLASS_CONFIGURATION\",\n    \"Index\": 87,\n    \"Node\": 23,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488323369926682,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1597173645\n}" 
[20200811 21:02:36.509 BST] [ozw.mqtt.publisher.value] [warning]: Bitset is Missing Position or Value Members, or Incorrect Types:  "{\n    \"Label\": \"Sensor is triggered the buzzer will alarm\",\n    \"Value\": [],\n    \"Units\": \"\",\n    \"ValueSet\": false,\n    \"ValuePolled\": false,\n    \"ChangeVerified\": false,\n    \"Min\": 0,\n    \"Max\": 55,\n    \"Type\": \"BitSet\",\n    \"Instance\": 1,\n    \"CommandClass\": \"COMMAND_CLASS_CONFIGURATION\",\n    \"Index\": 87,\n    \"Node\": 23,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488323369926682,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1597173645\n}" 
[20200811 21:02:36.511 BST] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 24488323369926682 
[20200811 21:02:36.516 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20200811 21:02:36.516 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.519 BST] [ozw.library] [debug]: Detail - Node: 23   ZW_SEND_DATA delivered to Z-Wave stack 
[20200811 21:02:36.519 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.579 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x07, 0x00, 0x13, 0x7e, 0x00, 0x00, 0x06, 0x93 
[20200811 21:02:36.579 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.582 BST] [ozw.library] [debug]: Detail - Node: 23   ZW_SEND_DATA Request with callback ID 0x7e received (expected 0x7e) 
[20200811 21:02:36.582 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.583 BST] [ozw.library] [info]: Info - Node: 23 Request RTT 79 Average Request RTT 428 
[20200811 21:02:36.583 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.585 BST] [ozw.library] [debug]: Detail - Node: 23   Expected callbackId was received 
[20200811 21:02:36.585 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.657 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x17, 0x05, 0x70, 0x06, 0x57, 0x01, 0x03, 0xd1 
[20200811 21:02:36.657 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.660 BST] [ozw.library] [info]: Info - Node: 23 Response RTT 156 Average Response RTT 649 
[20200811 21:02:36.660 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.662 BST] [ozw.library] [debug]: Detail - Node: 23 Value Updated: old value=-875575360, new value=3, type=bitset 
[20200811 21:02:36.663 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.664 BST] [ozw.library] [debug]: Detail - Node: 23 Changes to this value are not verified 
[20200811 21:02:36.665 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200811 21:02:36.666 BST] [ozw.daemon] [warning]: ============================= 
[20200811 21:02:36.666 BST] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace: 
[20200811 21:02:36.666 BST] [ozw.daemon] [warning]: ============================= 
[20200811 21:02:36.669 BST] [ozw.daemon] [warning]: home-assistant/core#1  0x00007f00b270a27d sp=0x00007f00af6f5b80 sigwaitinfo + 0x8 
[20200811 21:02:36.669 BST] [ozw.daemon] [warning]: home-assistant/core#2  0xaf6f627000577001 sp=0x00007f00af6f5b90  + 0x8 
[20200811 21:02:36.670 BST] [default] [warning]: Exiting....

Additional information

MartinHjelmare commented 4 years ago

What version of the zwave add-on are you running?

Dinth commented 4 years ago

Im running 0.5.2.

Dinth commented 4 years ago

Happened again: 

[20200813 19:02:57.736 BST] [ozw.library] [debug]: Detail - Node: 23 Value Updated: old value=0, new value=0, type=list 
[20200813 19:02:57.736 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:57.737 BST] [ozw.library] [debug]: Detail - Node: 23 Changes to this value are not verified 
[20200813 19:02:57.737 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:57.738 BST] [ozw.mqtt.publisher.value] [debug]: List Selected Value has Not Changed:  "Celsius" 
[20200813 19:02:57.738 BST] [ozw.library] [info]: Info - Node: 23 Received Configuration report: Parameter=64, Value=0 
[20200813 19:02:57.739 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:57.740 BST] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueRefreshed CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 64 
[20200813 19:02:57.740 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:57.741 BST] [ozw.mqtt.publisher] [debug]: Publishing Event valueRefreshed: 18014398905581588 
[20200813 19:02:57.741 BST] [ozw.notifications] [debug]: Notification pvt_valueRefreshed:  18014398905581588 Thread:  0x7f7f9226cd48 
[20200813 19:02:57.745 BST] [ozw.mqtt.publisher.value] [debug]: List Selected Value has Not Changed:  "Celsius" 
[20200813 19:02:57.747 BST] [ozw.mqtt.publisher] [debug]: Publishing Event valueRefreshed: 18014398905581588 
[20200813 19:02:58.301 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x17, 0x05, 0x70, 0x06, 0x40, 0x01, 0x00, 0xc5 
[20200813 19:02:58.301 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:58.304 BST] [ozw.library] [info]: Info - Node: 23 Response RTT 1010 Average Response RTT 714 
[20200813 19:02:58.305 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:58.306 BST] [ozw.library] [debug]: Detail - Node: 23 Value Updated: old value=0, new value=0, type=list 
[20200813 19:02:58.306 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:58.307 BST] [ozw.library] [debug]: Detail - Node: 23 Changes to this value are not verified 
[20200813 19:02:58.307 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:58.309 BST] [ozw.library] [info]: Info - Node: 23 Received Configuration report: Parameter=64, Value=0 
[20200813 19:02:58.309 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:58.310 BST] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueRefreshed CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 64 
[20200813 19:02:58.310 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:58.312 BST] [ozw.notifications] [debug]: Notification pvt_valueRefreshed:  18014398905581588 Thread:  0x7f7f9226cd48 
[20200813 19:02:58.316 BST] [ozw.mqtt.publisher.value] [debug]: List Selected Value has Not Changed:  "Celsius" 
[20200813 19:02:58.318 BST] [ozw.mqtt.publisher] [debug]: Publishing Event valueRefreshed: 18014398905581588 
[20200813 19:02:59.262 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x07, 0x00, 0x13, 0x7d, 0x00, 0x00, 0xc4, 0x52 
[20200813 19:02:59.263 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:59.265 BST] [ozw.library] [debug]: Detail - Node: 23   ZW_SEND_DATA Request with callback ID 0x7d received (expected 0x7d) 
[20200813 19:02:59.265 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:59.266 BST] [ozw.library] [info]: Info - Node: 23 Request RTT 1972 Average Request RTT 1188 
[20200813 19:02:59.266 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:02:59.267 BST] [ozw.library] [debug]: Detail - Node: 23   Expected callbackId was received 
[20200813 19:02:59.267 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.229 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x17, 0x05, 0x70, 0x06, 0x57, 0x01, 0x03, 0xd1 
[20200813 19:03:02.230 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.231 BST] [ozw.library] [info]: Info - Node: 23 Response RTT 4937 Average Response RTT 2825 
[20200813 19:03:02.231 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.232 BST] [ozw.library] [debug]: Detail - Node: 23 Initial read of value 
[20200813 19:03:02.232 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.233 BST] [ozw.library] [info]: Info - Node: 23 Received Configuration report: Parameter=87, Value=3 
[20200813 19:03:02.233 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.234 BST] [ozw.library] [debug]: Detail - Node: 23   Expected reply and command class was received 
[20200813 19:03:02.234 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.235 BST] [ozw.library] [debug]: Detail - Node: 23   Message transaction complete 
[20200813 19:03:02.235 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.235 BST] [ozw.library] [debug]: Detail - Node: 23 Removing current message 
[20200813 19:03:02.236 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.236 BST] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueChanged CC: COMMAND_CLASS_CONFIGURATION Instance: 1 Index: 87 
[20200813 19:03:02.236 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.237 BST] [ozw.library] [info]: Info - Node: 23 Sending (Query) message (Callback ID=0x7e, Expected Reply=0x04) - ConfigurationCmd_Get (Node=23): 0x01, 0x0a, 0x00, 0x13, 0x17, 0x03, 0x70, 0x05, 0x59, 0x25, 0x7e, 0x85 
[20200813 19:03:02.237 BST] [ozw.notifications] [debug]: Notification pvt_valueChanged:  24488323369926682 Thread:  0x7f7f9226cd48 
[20200813 19:03:02.237 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.238 BST] [ozw.library] [info]: Info - Node: 23 Encrypted Flag is 0 
[20200813 19:03:02.238 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.244 BST] [ozw.mqtt.publisher.value] [warning]: Bitset is Missing Position or Value Members, or Incorrect Types:  "{\n    \"Label\": \"Sensor is triggered the buzzer will alarm\",\n    \"Value\": [],\n    \"Units\": \"\",\n    \"ValueSet\": false,\n    \"ValuePolled\": false,\n    \"ChangeVerified\": false,\n    \"Min\": 0,\n    \"Max\": 55,\n    \"Type\": \"BitSet\",\n    \"Instance\": 1,\n    \"CommandClass\": \"COMMAND_CLASS_CONFIGURATION\",\n    \"Index\": 87,\n    \"Node\": 23,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488323369926682,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1597339227\n}" 
[20200813 19:03:02.244 BST] [ozw.mqtt.publisher.value] [warning]: Bitset is Missing Position or Value Members, or Incorrect Types:  "{\n    \"Label\": \"Sensor is triggered the buzzer will alarm\",\n    \"Value\": [],\n    \"Units\": \"\",\n    \"ValueSet\": false,\n    \"ValuePolled\": false,\n    \"ChangeVerified\": false,\n    \"Min\": 0,\n    \"Max\": 55,\n    \"Type\": \"BitSet\",\n    \"Instance\": 1,\n    \"CommandClass\": \"COMMAND_CLASS_CONFIGURATION\",\n    \"Index\": 87,\n    \"Node\": 23,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488323369926682,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1597339227\n}" 
[20200813 19:03:02.245 BST] [ozw.mqtt.publisher.value] [warning]: Bitset is Missing Position or Value Members, or Incorrect Types:  "{\n    \"Label\": \"Sensor is triggered the buzzer will alarm\",\n    \"Value\": [],\n    \"Units\": \"\",\n    \"ValueSet\": false,\n    \"ValuePolled\": false,\n    \"ChangeVerified\": false,\n    \"Min\": 0,\n    \"Max\": 55,\n    \"Type\": \"BitSet\",\n    \"Instance\": 1,\n    \"CommandClass\": \"COMMAND_CLASS_CONFIGURATION\",\n    \"Index\": 87,\n    \"Node\": 23,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488323369926682,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1597339227\n}" 
[20200813 19:03:02.245 BST] [ozw.mqtt.publisher.value] [warning]: Bitset is Missing Position or Value Members, or Incorrect Types:  "{\n    \"Label\": \"Sensor is triggered the buzzer will alarm\",\n    \"Value\": [],\n    \"Units\": \"\",\n    \"ValueSet\": false,\n    \"ValuePolled\": false,\n    \"ChangeVerified\": false,\n    \"Min\": 0,\n    \"Max\": 55,\n    \"Type\": \"BitSet\",\n    \"Instance\": 1,\n    \"CommandClass\": \"COMMAND_CLASS_CONFIGURATION\",\n    \"Index\": 87,\n    \"Node\": 23,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488323369926682,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1597339227\n}" 
[20200813 19:03:02.248 BST] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 24488323369926682 
[20200813 19:03:02.249 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20200813 19:03:02.249 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.250 BST] [ozw.library] [debug]: Detail - Node: 23   ZW_SEND_DATA delivered to Z-Wave stack 
[20200813 19:03:02.250 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.299 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x07, 0x00, 0x13, 0x7e, 0x00, 0x00, 0x05, 0x90 
[20200813 19:03:02.299 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.300 BST] [ozw.library] [debug]: Detail - Node: 23   ZW_SEND_DATA Request with callback ID 0x7e received (expected 0x7e) 
[20200813 19:03:02.300 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.301 BST] [ozw.library] [info]: Info - Node: 23 Request RTT 61 Average Request RTT 624 
[20200813 19:03:02.301 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.302 BST] [ozw.library] [debug]: Detail - Node: 23   Expected callbackId was received 
[20200813 19:03:02.302 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.365 BST] [ozw.library] [debug]: Detail - Node: 23   Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x17, 0x05, 0x70, 0x06, 0x57, 0x01, 0x03, 0xd1 
[20200813 19:03:02.365 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.367 BST] [ozw.library] [info]: Info - Node: 23 Response RTT 127 Average Response RTT 1476 
[20200813 19:03:02.367 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.367 BST] [ozw.library] [debug]: Detail - Node: 23 Value Updated: old value=1361946464, new value=3, type=bitset 
[20200813 19:03:02.368 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.368 BST] [ozw.library] [debug]: Detail - Node: 23 Changes to this value are not verified 
[20200813 19:03:02.368 BST] [ozw.logging] [debug]: popping Log Mesages 
[20200813 19:03:02.369 BST] [ozw.daemon] [warning]: ============================= 
[20200813 19:03:02.369 BST] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace: 
[20200813 19:03:02.369 BST] [ozw.daemon] [warning]: ============================= 
[20200813 19:03:02.371 BST] [ozw.daemon] [warning]: #1  0x00007f7f9221d27d sp=0x00007f7f8f208b80 sigwaitinfo + 0x8 
[20200813 19:03:02.371 BST] [ozw.daemon] [warning]: #2  0x8f20927000577001 sp=0x00007f7f8f208b90  + 0x8 
[20200813 19:03:02.371 BST] [default] [warning]: Exiting....

Sensor 23 is ZW122 Water Sensor 6by AEON Labs

MartinHjelmare commented 4 years ago

The issue is most likely in the upstream OpenZWave library or ozwdaemon. Try searching that issue tracker and possibly open an issue there if the issue is new: https://github.com/OpenZWave/open-zwave/issues

Thanks!

Lockszmith-GH commented 4 years ago

This is happening to me as well, I've opted to using the docker image from https://github.com/OpenZWave/qt-openzwave as that one seems to be working. it's currently tagged as build-170

For me it crashes when it initializes my Kiwkset deadbolt. I tried hard resetting, and confirmed that this happens after I add the deadbolt.

Hope this helps.

MartinHjelmare commented 4 years ago

So build 170 of the docker container for qt-openzwave works together with the Kwikset deadbolt?

Can you find what github commit that corresponds to here: https://github.com/OpenZWave/qt-openzwave/commits/master

Lockszmith-GH commented 4 years ago

Other than the date it was updated/pulled, couldn't find any correlation: image

Sorry, probably a question for the upstream git repo.

Spartan-II-117 commented 4 years ago

@Lockszmith what configuration did you use for the container? I'm having the same issue and would love to figure it out!

Spartan-II-117 commented 4 years ago

@MartinHjelmare I'd guess it's one of these if the fix was in 170? https://github.com/cgarwood/python-openzwave-mqtt/releases/tag/v1.0.4 annoying that thh different spots don't use a consistent numbering.

MartinHjelmare commented 4 years ago

python-openzwave-mqtt isn't used by the OpenZWave daemon. python-openzwave-mqtt is the library we use to transform the MQTT messages from the ozwdaemon to python objects.

Lockszmith-GH commented 4 years ago

My docker-compose for the ozwdaemon looks like this anonymized version right now:

version: "2.1"

services:
  ozwd:
    image: openzwave/ozwdaemon:latest
    container_name: "ozwd"
    security_opt:
      - seccomp:unconfined
    devices:
      - '/dev/serial/by-id/usb-Silicon_Labs_HubZ_Smart_Home_Controller_C0F007C9-if00-port0'
    volumes:
      - ./ozw:/opt/ozw/config
    ports:
      - "1983:1983"
    extra_hosts:
      - "ha-hostname.local:192.168.0.1" # This is where I setup the LAN IP address
    environment:
      MQTT_SERVER: 'ha-hostname.local'
      MQTT_USERNAME: 'username'
      MQTT_PASSWORD: 'password'
      USB_PATH: '/dev/serial/by-id/usb-Silicon_Labs_HubZ_Smart_Home_Controller_C0F007C9-if00-port0'
      OZW_NETWORK_KEY: '0x00, 0x01, 0x02, 0x03, 0x04, 0x05, 0x06, 0x07, 0x08, 0x09, 0x0A, 0x0B, 0x0C, 0x0D, 0x0E, 0x0F'
    restart: unless-stopped
Spartan-II-117 commented 4 years ago

Ok, Thanks!