open-zwave icon indicating copy to clipboard operation
open-zwave copied to clipboard

OZW crashing during initialization of Aeotec Water Sensor 6

Open Dinth opened this issue 3 years ago • 1 comments

Issue Guidelines

Hey. Im using ozw addon on home assistant and recently started experiencing weird crashes during node initialization, which seem to be specific to node 23 which is Aeotec Water Sensor 6. I have also logged an issue on HA's github but been advised that this is problably an upstream issue with OZW library or ozwdaemon - https://github.com/home-assistant/hassio-addons/issues/1536

Here are logs from last two crashes:

[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.... 
[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.... 

Dinth avatar Aug 14 '20 10:08 Dinth

I think I'm seeing the same thing. My Node 107 is also an Aeotec Water Sensor 6.

[20201101 19:32:36.217 GMT] [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\": 107,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488324779212826,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1604250527\n}" 
[20201101 19:32:36.218 GMT] [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\": 107,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488324779212826,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1604250527\n}" 
[20201101 19:32:36.218 GMT] [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\": 107,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488324779212826,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1604250527\n}" 
[20201101 19:32:36.218 GMT] [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\": 107,\n    \"Genre\": \"Config\",\n    \"Help\": \"What Sensors Trigger the Buzzer\",\n    \"ValueIDKey\": 24488324779212826,\n    \"ReadOnly\": false,\n    \"WriteOnly\": false,\n    \"Event\": \"valueAdded\",\n    \"TimeStamp\": 1604250527\n}" 
[20201101 19:32:36.226 GMT] [ozw.mqtt.publisher] [debug]: Publishing Event valueChanged: 24488324779212826 
[20201101 19:32:36.333 GMT] [ozw.library] [debug]: Detail - Node: 107   Received: 0x01, 0x07, 0x00, 0x13, 0x13, 0x00, 0x00, 0x2b, 0xd3 
[20201101 19:32:36.333 GMT] [ozw.logging] [debug]: popping Log Mesages 
[20201101 19:32:36.338 GMT] [ozw.library] [debug]: Detail - Node: 107   ZW_SEND_DATA Request with callback ID 0x13 received (expected 0x13) 
[20201101 19:32:36.338 GMT] [ozw.logging] [debug]: popping Log Mesages 
[20201101 19:32:36.342 GMT] [ozw.library] [info]: Info - Node: 107 Request RTT 441 Average Request RTT 281 
[20201101 19:32:36.342 GMT] [ozw.logging] [debug]: popping Log Mesages 
[20201101 19:32:36.345 GMT] [ozw.library] [debug]: Detail - Node: 107   Expected callbackId was received 
[20201101 19:32:36.345 GMT] [ozw.logging] [debug]: popping Log Mesages 
[20201101 19:32:36.420 GMT] [ozw.library] [debug]: Detail - Node: 107   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x6b, 0x05, 0x70, 0x06, 0x57, 0x01, 0x03, 0xbd 
[20201101 19:32:36.420 GMT] [ozw.logging] [debug]: popping Log Mesages 
[20201101 19:32:36.425 GMT] [ozw.library] [info]: Info - Node: 107 Response RTT 525 Average Response RTT 351 
[20201101 19:32:36.425 GMT] [ozw.logging] [debug]: popping Log Mesages 
[20201101 19:32:36.430 GMT] [ozw.library] [debug]: Detail - Node: 107 Value Updated: old value=-1277722272, new value=3, type=bitset 
[20201101 19:32:36.430 GMT] [ozw.logging] [debug]: popping Log Mesages 
[20201101 19:32:36.433 GMT] [ozw.library] [debug]: Detail - Node: 107 Changes to this value are not verified 
[20201101 19:32:36.434 GMT] [ozw.logging] [debug]: popping Log Mesages 
[20201101 19:32:36.437 GMT] [ozw.daemon] [warning]: ============================= 
[20201101 19:32:36.437 GMT] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace: 
[20201101 19:32:36.437 GMT] [ozw.daemon] [warning]: ============================= 
[20201101 19:32:36.441 GMT] [ozw.daemon] [warning]: #1  0x0000007fbe6e3a08 sp=0x0000007fbb59de70 __setjmp + 0x38 
[20201101 19:32:36.444 GMT] [ozw.daemon] [warning]: #2  0x0000007fbdbf5a38 sp=0x0000007fbb59f0d0 OpenZWave::Internal::Bitfield::GetValue() const + 0x20 
[20201101 19:32:36.445 GMT] [ozw.daemon] [warning]: #3  0x0000007fbdb9347c sp=0x0000007fbb59f0d0 OpenZWave::Internal::VC::Value::VerifyRefreshedValue(void*, void*, void*, void*, OpenZWave::ValueID::ValueType, int, int, int, int) + 0x20c 
[20201101 19:32:36.446 GMT] [ozw.daemon] [warning]: #4  0x0000007fbdb8e0b4 sp=0x0000007fbb59f150 OpenZWave::Internal::VC::ValueBitSet::OnValueRefreshed(unsigned int) + 0x44 
[20201101 19:32:36.447 GMT] [ozw.daemon] [warning]: #5  0x0000007fbdb52b18 sp=0x0000007fbb59f190 OpenZWave::Internal::CC::Configuration::HandleMsg(unsigned char const*, unsigned int, unsigned int) + 0x140 
[20201101 19:32:36.447 GMT] [ozw.daemon] [warning]: #6  0x0000007fbdbdc67c sp=0x0000007fbb59f280 OpenZWave::Node::ApplicationCommandHandler(unsigned char const*, bool) + 0xac 
[20201101 19:32:36.448 GMT] [ozw.daemon] [warning]: #7  0x0000007fbdbb4980 sp=0x0000007fbb59f320 OpenZWave::Driver::ProcessMsg(unsigned char*, unsigned char) + 0x218 
[20201101 19:32:36.449 GMT] [ozw.daemon] [warning]: #8  0x0000007fbdbb5c18 sp=0x0000007fbb59f480 OpenZWave::Driver::ReadMsg() + 0x630 
[20201101 19:32:36.449 GMT] [ozw.daemon] [warning]: #9  0x0000007fbdbb785c sp=0x0000007fbb59f930 OpenZWave::Driver::DriverThreadProc(OpenZWave::Internal::Platform::Event*) + 0x2bc 
[20201101 19:32:36.450 GMT] [ozw.daemon] [warning]: #10 0x0000007fbdb9e6a4 sp=0x0000007fbb59fa30 OpenZWave::Internal::Platform::ThreadImpl::Run() + 0x24 
[20201101 19:32:36.451 GMT] [ozw.daemon] [warning]: #11 0x0000007fbdb9e6c4 sp=0x0000007fbb59fa50 OpenZWave::Internal::Platform::ThreadImpl::ThreadProc(void*) + 0xc 
[20201101 19:32:36.452 GMT] [ozw.daemon] [warning]: #12 0x0000007fbe6f29d4 sp=0x0000007fbb59fa60 pthread_exit + 0x238 
[20201101 19:32:36.453 GMT] [ozw.daemon] [warning]: #13 0x0000007fbe6f29d4 sp=0x0000007fbb59fa60 pthread_exit + 0x238 
[20201101 19:32:36.453 GMT] [default] [warning]: Exiting.... 
1604259156: Socket error on client qt-openzwave-1, disconnecting.
In exit
[cont-finish.d] executing container finish scripts...
[cont-finish.d] mqtt.sh: executing... 
1604259156: mosquitto version 1.6.8 terminating
1604259156: Saving in-memory database to /data/mosquitto.db.
[19:32:37] INFO: Ensure upstream MQTT server has the correct OZW status
[cont-finish.d] mqtt.sh: exited 0.
[cont-finish.d] done.
[s6-finish] waiting for services.
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.

evansj avatar Nov 01 '20 22:11 evansj