search

OpenZWave / qt-openzwave

QT5 Wrapper for OpenZWave
GNU Lesser General Public License v3.0
105 stars 30 forks source link

Repeated crashes (std::out_of_range) after adding Dome Door / Window Sensor Pro #107

Open autopad opened 4 years ago

autopad commented 4 years ago

After adding a Dome Door / Window Sensor Pro to the network using Add Node within ozw-admin, I am seeing the add-on crash within an hour or two of it starting up. The node has not yet been written to the ozwcache file.

Prior log entries

``` [20200615 3:17:21.633 EDT] [ozw.library] [info]: Info - Node: 23 Response RTT 210 Average Response RTT 205 [20200615 3:17:21.637 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 1688850260607000 [20200615 3:17:21.637 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 1970325237317656 [20200615 3:17:21.639 EDT] [ozw.library] [warning]: Warning - Node: 0 SensorMultiLevelCCTypes::GetSensorUnit - Unknown SensorScale 255 [20200615 3:17:21.645 EDT] [ozw.library] [info]: Info - Node: 23 Setting SensorMultiLevel Default Scale to: (-1) [20200615 3:17:21.649 EDT] [ozw.library] [warning]: Warning - Node: 0 SensorMultiLevelCCTypes::GetSensorUnit - Unknown SensorScale 255 [20200615 3:17:21.654 EDT] [ozw.library] [warning]: Warning - Node: 0 SensorMultiLevelCCTypes::GetSensorUnit - Unknown SensorScale 255 [20200615 3:17:21.660 EDT] [ozw.library] [warning]: Warning - Node: 0 Attempt to get a Invalid Label from ValueList [20200615 3:17:21.666 EDT] [ozw.library] [warning]: Warning - Node: 0 Attempt to Set a Invalid Label for ValueList in OnValueRefreshed [20200615 3:17:21.671 EDT] [ozw.library] [debug]: Detail - Node: 23 Expected reply and command class was received [20200615 3:17:21.676 EDT] [ozw.library] [debug]: Detail - Node: 23 Message transaction complete [20200615 3:17:21.680 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 1970325237317656 [20200615 3:17:21.680 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 2251800214028308 [20200615 3:17:21.682 EDT] [ozw.library] [debug]: Detail - Node: 23 Removing current message [20200615 3:17:21.687 EDT] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueAdded CC: COMMAND_CLASS_SENSOR_MULTILEVEL Instance: 1 Index: 1 [20200615 3:17:21.692 EDT] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueAdded CC: COMMAND_CLASS_SENSOR_MULTILEVEL Instance: 1 Index: 256 [20200615 3:17:21.698 EDT] [ozw.library] [info]: Info - Node: 23 Sending (Query) message (Callback ID=0xea, Expected Reply=0x04) - ZWavePlusInfoCmd_Get (Node=23): 0x01, 0x09, 0x00, 0x13, 0x17, 0x02, 0x5e, 0x01, 0x25, 0xea, 0x60 [20200615 3:17:21.702 EDT] [ozw.library] [info]: Info - Node: 23 Encrypted Flag is 0 [20200615 3:17:21.714 EDT] [ozw.library] [debug]: Detail - Node: 23 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 [20200615 3:17:21.719 EDT] [ozw.library] [debug]: Detail - Node: 23 ZW_SEND_DATA delivered to Z-Wave stack [20200615 3:17:21.724 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 2251800214028308 [20200615 3:17:21.725 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 2533275190738966 [20200615 3:17:21.753 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 2533275190738966 [20200615 3:17:21.754 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 392167441 [20200615 3:17:21.780 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 392167441 [20200615 3:17:21.781 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 281475377332243 [20200615 3:17:21.807 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 281475377332243 [20200615 3:17:21.807 EDT] [ozw.library] [debug]: Detail - Node: 23 Received: 0x01, 0x07, 0x00, 0x13, 0xea, 0x00, 0x00, 0x0a, 0x0b [20200615 3:17:21.807 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 562950354042899 [20200615 3:17:21.812 EDT] [ozw.library] [debug]: Detail - Node: 23 ZW_SEND_DATA Request with callback ID 0xea received (expected 0xea) [20200615 3:17:21.816 EDT] [ozw.library] [info]: Info - Node: 23 Request RTT 109 Average Request RTT 117 [20200615 3:17:21.821 EDT] [ozw.library] [debug]: Detail - Node: 23 Expected callbackId was received [20200615 3:17:21.843 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 562950354042899 [20200615 3:17:21.843 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 844425330753555 [20200615 3:17:21.886 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 844425330753555 [20200615 3:17:21.887 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 1125900307464211 [20200615 3:17:21.890 EDT] [ozw.library] [debug]: Detail - Node: 23 Received: 0x01, 0x0f, 0x00, 0x04, 0x00, 0x17, 0x09, 0x5e, 0x02, 0x01, 0x06, 0x00, 0x0c, 0x06, 0x0c, 0x06, 0xb1 [20200615 3:17:21.895 EDT] [ozw.library] [info]: Info - Node: 23 Response RTT 188 Average Response RTT 196 [20200615 3:17:21.900 EDT] [ozw.library] [info]: Info - Node: 23 ZW+ Info - Version 1, Role 6, NodeType 0, InstallerIcon 3078, deviceType 3078 [20200615 3:17:21.904 EDT] [ozw.library] [debug]: Detail - Node: 23 Initial read of value [20200615 3:17:21.909 EDT] [ozw.library] [debug]: Detail - Node: 23 Initial read of value [20200615 3:17:21.914 EDT] [ozw.library] [debug]: Detail - Node: 23 Initial read of value [20200615 3:17:21.919 EDT] [ozw.library] [debug]: Detail - Node: 23 Expected reply and command class was received [20200615 3:17:21.924 EDT] [ozw.library] [debug]: Detail - Node: 23 Message transaction complete [20200615 3:17:21.929 EDT] [ozw.library] [debug]: Detail - Node: 23 Removing current message [20200615 3:17:21.930 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 1125900307464211 [20200615 3:17:21.931 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 400621587 [20200615 3:17:21.934 EDT] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueChanged CC: COMMAND_CLASS_ZWAVEPLUS_INFO Instance: 1 Index: 0 [20200615 3:17:21.940 EDT] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueChanged CC: COMMAND_CLASS_ZWAVEPLUS_INFO Instance: 1 Index: 1 [20200615 3:17:21.945 EDT] [ozw.library] [debug]: Detail - Node: 23 Notification: ValueChanged CC: COMMAND_CLASS_ZWAVEPLUS_INFO Instance: 1 Index: 2 [20200615 3:17:21.950 EDT] [ozw.library] [info]: Info - Node: 23 Sending (Query) message (Callback ID=0xeb, Expected Reply=0x04) - AlarmCmd_SupportedGet (Node=23): 0x01, 0x09, 0x00, 0x13, 0x17, 0x02, 0x71, 0x07, 0x25, 0xeb, 0x48 [20200615 3:17:21.955 EDT] [ozw.library] [info]: Info - Node: 23 Encrypted Flag is 0 [20200615 3:17:21.968 EDT] [ozw.library] [debug]: Detail - Node: 23 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 [20200615 3:17:21.974 EDT] [ozw.library] [debug]: Detail - Node: 23 ZW_SEND_DATA delivered to Z-Wave stack [20200615 3:17:21.978 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 400621587 [20200615 3:17:21.979 EDT] [ozw.notifications] [debug]: Notification pvt_valueChanged: 400621587 [20200615 3:17:21.990 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 400654359 [20200615 3:17:22.030 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 400654359 [20200615 3:17:22.030 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 281475377365015 [20200615 3:17:22.063 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 281475377365015 [20200615 3:17:22.064 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 562950354075671 [20200615 3:17:22.067 EDT] [ozw.library] [debug]: Detail - Node: 23 Received: 0x01, 0x07, 0x00, 0x13, 0xeb, 0x00, 0x00, 0x0a, 0x0a [20200615 3:17:22.072 EDT] [ozw.library] [debug]: Detail - Node: 23 ZW_SEND_DATA Request with callback ID 0xeb received (expected 0xeb) [20200615 3:17:22.078 EDT] [ozw.library] [info]: Info - Node: 23 Request RTT 118 Average Request RTT 117 [20200615 3:17:22.083 EDT] [ozw.library] [debug]: Detail - Node: 23 Expected callbackId was received [20200615 3:17:22.109 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 562950354075671 [20200615 3:17:22.109 EDT] [ozw.notifications] [debug]: Notification pvt_valueChanged: 562950353747987 [20200615 3:17:22.123 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 281475367583762 [20200615 3:17:22.148 EDT] [ozw.library] [debug]: Detail - Node: 23 Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x17, 0x04, 0x71, 0x08, 0x01, 0xc0, 0x5a [20200615 3:17:22.154 EDT] [ozw.library] [info]: Info - Node: 23 Response RTT 194 Average Response RTT 195 [20200615 3:17:22.160 EDT] [ozw.library] [info]: Info - Node: 23 Received supported alarm types [20200615 3:17:22.163 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 281475367583762 [20200615 3:17:22.164 EDT] [ozw.notifications] [debug]: Notification pvt_valueAdded: 72057594437189652 [20200615 3:17:22.167 EDT] [ozw.library] [info]: Info - Node: 23 AlarmType: Access Control [20200615 3:17:22.171 EDT] [ozw.library] [debug]: Detail - Node: 23 Queuing (Send) AlarmCmd_Event_Supported_Get (Node=23): 0x01, 0x0a, 0x00, 0x13, 0x17, 0x03, 0x71, 0x01, 0x06, 0x25, 0xf3, 0x52 ``` 

[20200615 3:17:22.177 EDT] [ozw.library] [info]: Info - Node: 23    AlarmType: Home Security
terminate called after throwing an instance of 'std::out_of_range'
terminate called recursively
[20200615 3:17:22.310 EDT] [ozw.daemon] [warning]: =============================
[20200615 3:17:22.310 EDT] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace:
[20200615 3:17:22.311 EDT] [ozw.daemon] [warning]: =============================
[20200615 3:17:22.336 EDT] [ozw.daemon] [warning]: #1  0x0000007fb8f93a08 sp=0x0000007fcac53550 __setjmp + 0x38
[20200615 3:17:22.337 EDT] [ozw.daemon] [warning]: #2  0x0000007fb8f93a90 sp=0x0000007fcac547b0 sigsetjmp + 0x80
[20200615 3:17:22.339 EDT] [ozw.daemon] [warning]: #3  0x0000007fb8f93c24 sp=0x0000007fcac547b0 raise + 0x40
[20200615 3:17:22.340 EDT] [default] [warning]: Exiting....
1592205442: Socket error on client qt-openzwave-1, disconnecting.
[20200615 3:17:22.531 EDT] [ozw.manager] [warning]: WebSocket Client Error  QAbstractSocket::RemoteHostClosedError
[20200615 3:17:22.531 EDT] [ozw.manager] [debug]: QTOZWManager Ready!
[20200615 3:17:22.532 EDT] [ozw.manager] [debug]: QTOZWLog Ready!
[20200615 3:17:22.539 EDT] [ozw.manager] [debug]: QTOZWOptions Ready!
[20200615 3:17:22.540 EDT] [ozwadmin] [debug]: Remote Connection Status:  QTOZWManager::ConnectionErrorState QAbstractSocket::RemoteHostClosedError
1592205442: mosquitto version 1.6.8 terminating
1592205442: Saving in-memory database to /data/mosquitto.db.
In exit
Terminating child 499
[cont-finish.d] executing container finish scripts...
[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.
Fishwaldo commented 4 years ago

Please try with the official ozwdaemon image as the HACS one does not include a backtrace I can use.

autopad commented 4 years ago

Just tried, first with prior image I had (ozwdaemon: 0.1.111, OpenZWave: 1.6.1133, QT-OpenZWave: 1.1.0, QT: 5.12.5) and after pulling the latest image (ozwdaemon: 0.1.138, OpenZWave: 1.6.1142, QT-OpenZWave: 1.2.0, QT: 5.12.5).

Results look outwardly very similar in both cases to the Home Assistant installation as to the timing of the crash. The logs show the backtraces being created and the files are present locally, but it's not clear that they uploaded successfully. The last two lines in both logs are:

Failed to open curl lib from binary, use libcurl.so instead
realloc(): invalid pointer

I assume that's an unrelated issue. If it's relevant, I did follow the docker instructions in re: seccomp=unconfined.

autopad commented 4 years ago

If there's anything else I can provide to document the issue or if this would be better noted elsewhere, please let me know. I've left the battery removed from this device but today reconnected and tried again with the latest official image (as opposed to the Home Assistant beta addon). Same outcome.

ozwdaemon 0.1.149 OpenZWave 1.6.1173

[20200627 21:11:32.003 UTC] [ozw.library] [info]: Info - Node: 24 Encrypted Flag is 0
[20200627 21:11:32.011 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 417398803
[20200627 21:11:32.012 UTC] [ozw.notifications] [debug]: Notification pvt_valueChanged:  417398803 Thread:  0x7f99c77010
[20200627 21:11:32.013 UTC] [ozw.library] [debug]: Detail - Node: 24   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
[20200627 21:11:32.014 UTC] [ozw.library] [debug]: Detail - Node: 24   ZW_SEND_DATA delivered to Z-Wave stack
[20200627 21:11:32.019 UTC] [ozw.notifications] [debug]: Notification pvt_valueAdded: 417431575 Thread:  0x7f99c77010
[20200627 21:11:32.032 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 417431575
[20200627 21:11:32.033 UTC] [ozw.notifications] [debug]: Notification pvt_valueAdded: 281475394142231 Thread:  0x7f99c77010
[20200627 21:11:32.047 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 281475394142231
[20200627 21:11:32.048 UTC] [ozw.notifications] [debug]: Notification pvt_valueAdded: 562950370852887 Thread:  0x7f99c77010
[20200627 21:11:32.061 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 562950370852887
[20200627 21:11:32.063 UTC] [ozw.notifications] [debug]: Notification pvt_valueAdded: 281475384360978 Thread:  0x7f99c77010
[20200627 21:11:32.076 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 281475384360978
[20200627 21:11:32.077 UTC] [ozw.notifications] [debug]: Notification pvt_valueAdded: 72057594453966868 Thread:  0x7f99c77010
[20200627 21:11:32.079 UTC] [ozw.library] [debug]: Detail - Node: 24   Received: 0x01, 0x07, 0x00, 0x13, 0x84, 0x00, 0x00, 0x07, 0x68
[20200627 21:11:32.080 UTC] [ozw.library] [debug]: Detail - Node: 24   ZW_SEND_DATA Request with callback ID 0x84 received (expected 0x84)
[20200627 21:11:32.081 UTC] [ozw.library] [info]: Info - Node: 24 Request RTT 76 Average Request RTT 95
[20200627 21:11:32.083 UTC] [ozw.library] [debug]: Detail - Node: 24   Expected callbackId was received
[20200627 21:11:32.158 UTC] [ozw.library] [debug]: Detail - Node: 24   Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x18, 0x04, 0x71, 0x08, 0x01, 0xc0, 0x55
[20200627 21:11:32.159 UTC] [ozw.library] [info]: Info - Node: 24 Response RTT 154 Average Response RTT 174
[20200627 21:11:32.160 UTC] [ozw.library] [info]: Info - Node: 24 Received supported alarm types
[20200627 21:11:32.160 UTC] [ozw.library] [info]: Info - Node: 24       AlarmType: Access Control
[20200627 21:11:34.172 UTC] [ozw.library] [info]: Info - Node: 0 Lookup for 0201.0003.021f.db.openzwave.com returned 4
terminate called after throwing an instance of 'std::out_of_range'
terminate called recursively
[20200627 21:12:41.436 UTC] [ozw.daemon] [warning]: =============================
[20200627 21:12:41.436 UTC] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace:
[20200627 21:12:41.437 UTC] [ozw.daemon] [warning]: =============================
[20200627 21:12:41.537 UTC] [ozw.daemon] [warning]: #1  0x000000557f2fdcfc sp=0x0000005590efd980 dumpCallback(google_breakpad::MinidumpDescriptor const&, void*, bool) + 0x24
[20200627 21:12:41.539 UTC] [ozw.daemon] [warning]: #2  0x000000557f313a84 sp=0x0000005590efdc00 google_breakpad::ExceptionHandler::GenerateDump(google_breakpad::ExceptionHandler::CrashContext*) + 0x31c
[20200627 21:12:41.541 UTC] [ozw.daemon] [warning]: #3  0x000000557f313df8 sp=0x0000005590efdc80 google_breakpad::ExceptionHandler::SignalHandler(int, siginfo_t*, void*) + 0xb8
[20200627 21:12:41.543 UTC] [ozw.daemon] [warning]: #4  0x0000007f9cfd7658 sp=0x0000005590efdd70  + 0xb8
[20200627 21:12:41.545 UTC] [ozw.daemon] [warning]: #5  0x0000007f9c32ed08 sp=0x0000007fe519ef10 gsignal + 0xe0
[20200627 21:12:41.546 UTC] [ozw.daemon] [warning]: #6  0x0000007f9c31bea8 sp=0x0000007fe519f040 abort + 0x110
[20200627 21:12:41.582 UTC] [ozw.daemon] [warning]: #7  0x0000007f9c508184 sp=0x0000007fe519f180 __gnu_cxx::__verbose_terminate_handler() + 0x124
[20200627 21:12:41.585 UTC] [ozw.daemon] [warning]: #8  0x0000007f9c505cfc sp=0x0000007fe519f1c0 std::rethrow_exception(std::__exception_ptr::exception_ptr) + 0x9c
[20200627 21:12:41.587 UTC] [ozw.daemon] [warning]: #9  0x0000007f9c505d60 sp=0x0000007fe519f1d0 std::terminate() + 0x10
[20200627 21:12:41.589 UTC] [ozw.daemon] [warning]: #10 0x0000007f9c5060c0 sp=0x0000007fe519f1e0 __cxa_rethrow + 0x60
[20200627 21:12:41.590 UTC] [ozw.daemon] [warning]: #11 0x0000007f9c508120 sp=0x0000007fe519f200 __gnu_cxx::__verbose_terminate_handler() + 0xc0
[20200627 21:12:41.592 UTC] [ozw.daemon] [warning]: #12 0x0000007f9c505cfc sp=0x0000007fe519f240 std::rethrow_exception(std::__exception_ptr::exception_ptr) + 0x9c
[20200627 21:12:41.593 UTC] [ozw.daemon] [warning]: #13 0x0000007f9c505d60 sp=0x0000007fe519f250 std::terminate() + 0x10
[20200627 21:12:41.595 UTC] [ozw.daemon] [warning]: #14 0x0000007f9c506054 sp=0x0000007fe519f260 __cxa_throw + 0x64
[20200627 21:12:41.597 UTC] [ozw.daemon] [warning]: #15 0x0000007f9c52e710 sp=0x0000007fe519f290 std::__throw_out_of_range_fmt(char const*, ...) + 0xd0
[20200627 21:12:41.681 UTC] [ozw.daemon] [warning]: #16 0x0000007f9c20bf70 sp=0x0000007fe519f600 OpenZWave::Internal::VC::ValueList::GetItem() const + 0x48
[20200627 21:12:41.703 UTC] [ozw.daemon] [warning]: #17 0x0000007f9c22dac8 sp=0x0000007fe519f620 OpenZWave::Manager::GetValueListSelection(OpenZWave::ValueID const&, std::__cxx11::basic_string<char, std::char_traits<char>, std::allocator<char> >*) + 0x68
[20200627 21:12:41.916 UTC] [ozw.daemon] [warning]: #18 0x0000007f9cef73fc sp=0x0000007fe519f6d0 QTOZWManager_Internal::convertValueID(unsigned long long) + 0x3fc
[20200627 21:12:41.928 UTC] [ozw.daemon] [warning]: #19 0x0000007f9cef8c4c sp=0x0000007fe519f800 QTOZWManager_Internal::pvt_valueAdded(unsigned long long) + 0x44c
[20200627 21:12:41.929 UTC] [ozw.daemon] [warning]: #20 0x0000007f9c9229ec sp=0x0000007fe519f8d0 QObject::event(QEvent*) + 0x1fc
[20200627 21:12:42.007 UTC] [ozw.daemon] [warning]: #21 0x0000007f9c8f52d8 sp=0x0000007fe519f9d0 QCoreApplication::notifyInternal2(QObject*, QEvent*) + 0x150
[20200627 21:12:42.010 UTC] [ozw.daemon] [warning]: #22 0x0000007f9c8f8250 sp=0x0000007fe519fa40 QCoreApplicationPrivate::sendPostedEvents(QObject*, int, QThreadData*) + 0x168
[20200627 21:12:42.012 UTC] [ozw.daemon] [warning]: #23 0x0000007f9c950e60 sp=0x0000007fe519fb10 QEventDispatcherGlibPrivate::runTimersOnceWithNormalPriority() + 0x38
[20200627 21:12:42.028 UTC] [ozw.daemon] [warning]: #24 0x0000007f9b93749c sp=0x0000007fe519fb30 g_main_context_dispatch + 0x274
[20200627 21:12:42.071 UTC] [ozw.daemon] [warning]: #25 0x0000007f9b937708 sp=0x0000007fe519fbc0 g_main_context_dispatch + 0x4e0
[20200627 21:12:42.073 UTC] [ozw.daemon] [warning]: #26 0x0000007f9b9377ac sp=0x0000007fe519fc20 g_main_context_iteration + 0x34
[20200627 21:12:42.076 UTC] [ozw.daemon] [warning]: #27 0x0000007f9c950314 sp=0x0000007fe519fc40 QEventDispatcherGlib::processEvents(QFlags<QEventLoop::ProcessEventsFlag>) + 0x54
[20200627 21:12:42.078 UTC] [ozw.daemon] [warning]: #28 0x0000007f9c8f3ae8 sp=0x0000007fe519fc90 QEventLoop::exec(QFlags<QEventLoop::ProcessEventsFlag>) + 0xf8
[20200627 21:12:42.080 UTC] [ozw.daemon] [warning]: #29 0x0000007f9c8fc0fc sp=0x0000007fe519fd20 QCoreApplication::exec() + 0x9c
[20200627 21:12:42.082 UTC] [ozw.daemon] [warning]: #30 0x000000557f2b8ec4 sp=0x0000007fe519fd90 main + 0x1c64
[20200627 21:12:42.083 UTC] [ozw.daemon] [warning]: #31 0x0000007f9c31c2ec sp=0x0000007fe51a02f0 __libc_start_main + 0xe4
[20200627 21:12:42.085 UTC] [ozw.daemon] [warning]: #32 0x000000557f2bb380 sp=0x0000007fe51a0430 _start + 0x34
[20200627 21:12:42.085 UTC] [ozw.daemon] [warning]: dumpCallback Succeeded:  true  at  /opt/ozw/config/crashes//18d06c8e-f6d7-41f5-3d13d09b-55b0d6bb.dmp
[20200627 21:12:42.086 UTC] [ozw.daemon] [warning]: Uploading MiniDump to  https://sentry.io/api/1868130/minidump/?sentry_key=e086ba93030843199aab391947d205da
Failed to open curl lib from binary, use libcurl.so instead
sentry-io[bot] commented 4 years ago

Sentry issue: QT-OPENZWAVE-4F

Fishwaldo commented 4 years ago

I've Pushed a possible fix for this - Should appear in Build 150.

autopad commented 4 years ago

Thank you @Fishwaldo

I pulled the latest image (including ozwdaemon 0.1.150) and am having an issue similar to https://github.com/OpenZWave/qt-openzwave/issues/114. I haven't yet reconnected the offending device. I will try again on different hardware tomorrow to rule that out and will report the results.

2020-07-03 03:43:01,273 INFO Set uid to user 0 succeeded
2020-07-03 03:43:01,293 INFO supervisord started with pid 1
2020-07-03 03:43:02,304 INFO spawned: 'supervisord-watchdog' with pid 10
2020-07-03 03:43:02,316 INFO spawned: 'ozwdaemon' with pid 11
2020-07-03 03:43:02,326 INFO spawned: 'ozw-admin' with pid 12
2020-07-03 03:43:02,341 INFO spawned: 'websockify' with pid 13
2020-07-03 03:43:03,775 INFO success: supervisord-watchdog entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2020-07-03 03:43:03,791 INFO success: ozw-admin entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2020-07-03 03:43:03,792 INFO success: websockify entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2020-07-03 03:43:12,309 INFO success: ozwdaemon entered RUNNING state, process has stayed up for > than 10 seconds (startsecs)
2020-07-03 03:45:15,901 INFO exited: ozwdaemon (exit status 255; not expected)
2020-07-03 03:45:16,921 INFO spawned: 'ozwdaemon' with pid 29
2020-07-03 03:45:21,000 INFO exited: ozw-admin (exit status 0; expected)
2020-07-03 03:45:21,045 INFO spawned: 'ozw-admin' with pid 36
2020-07-03 03:45:22,039 INFO success: ozw-admin entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2020-07-03 03:45:26,938 INFO success: ozwdaemon entered RUNNING state, process has stayed up for > than 10 seconds (startsecs)
2020-07-03 03:47:27,301 INFO exited: ozwdaemon (exit status 255; not expected)
2020-07-03 03:47:28,313 INFO spawned: 'ozwdaemon' with pid 39
2020-07-03 03:47:38,309 INFO success: ozwdaemon entered RUNNING state, process has stayed up for > than 10 seconds (startsecs)
2020-07-03 03:47:46,235 INFO exited: ozw-admin (exit status 0; expected)
2020-07-03 03:47:46,633 INFO spawned: 'ozw-admin' with pid 46
2020-07-03 03:47:47,664 INFO success: ozw-admin entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2020-07-03 03:49:34,667 INFO exited: ozwdaemon (exit status 255; not expected)
2020-07-03 03:49:35,680 INFO spawned: 'ozwdaemon' with pid 47
2020-07-03 03:49:46,166 INFO success: ozwdaemon entered RUNNING state, process has stayed up for > than 10 seconds (startsecs)
2020-07-03 03:51:58,430 INFO exited: ozwdaemon (exit status 255; not expected)
2020-07-03 03:51:59,441 INFO spawned: 'ozwdaemon' with pid 54
2020-07-03 03:52:09,444 INFO success: ozwdaemon entered RUNNING state, process has stayed up for > than 10 seconds (startsecs)
2020-07-03 03:54:10,977 INFO exited: ozwdaemon (exit status 255; not expected)
[20200703 3:57:34.539 UTC] [ozw.daemon] [warning]: dumpCallback Succeeded:  true  at  /opt/ozw/config/crashes//00f7e47b-9e67-4e79-da4071b4-33786971.dmp
autopad commented 4 years ago

Saw same behavior as before after adding the problematic device back into the network:

[20200705 2:38:11.359 UTC] [ozw.library] [info]: Info - Node: 30 Response RTT 146 Average Response RTT 169
[20200705 2:38:11.359 UTC] [ozw.library] [info]: Info - Node: 30 Received supported alarm Event types for AlarmType Access Control (6)
[20200705 2:38:11.360 UTC] [ozw.library] [info]: Info - Node: 30    Event Type 0: Clear 
[20200705 2:38:11.360 UTC] [ozw.library] [info]: Info - Node: 30    Event Type 22: Door/Window Open 
[20200705 2:38:11.360 UTC] [ozw.library] [info]: Info - Node: 30    Event Type 23: Door/Window Closed 
[20200705 2:38:11.361 UTC] [ozw.library] [debug]: Detail - Node: 30   Expected reply and command class was received
[20200705 2:38:11.362 UTC] [ozw.library] [debug]: Detail - Node: 30   Message transaction complete
[20200705 2:38:11.362 UTC] [ozw.library] [debug]: Detail - Node: 30 Removing current message
[20200705 2:38:11.363 UTC] [ozw.library] [debug]: Detail - Node: 30 Notification: ValueAdded CC: COMMAND_CLASS_NOTIFICATION Instance: 1 Index: 256
[20200705 2:38:11.364 UTC] [ozw.library] [debug]: Detail - Node: 30 Notification: ValueAdded CC: COMMAND_CLASS_NOTIFICATION Instance: 1 Index: 6
terminate called after throwing an instance of 'std::out_of_range'
terminate called recursively
[20200705 2:38:11.666 UTC] [ozw.daemon] [warning]: =============================
[20200705 2:38:11.667 UTC] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace:
[20200705 2:38:11.667 UTC] [ozw.daemon] [warning]: =============================
[20200705 2:38:11.693 UTC] [ozw.daemon] [warning]: #1  0x00000000004e8811 sp=0x0000000000d1b320 dumpCallback(google_breakpad::MinidumpDescriptor const&, void*, bool) + 0x18
[20200705 2:38:11.696 UTC] [ozw.daemon] [warning]: #2  0x00000000004f8d6d sp=0x0000000000d1b4e0 google_breakpad::ExceptionHandler::GenerateDump(google_breakpad::ExceptionHandler::CrashContext*) + 0x198
[20200705 2:38:11.698 UTC] [ozw.daemon] [warning]: #3  0x00000000004f9029 sp=0x0000000000d1b528 google_breakpad::ExceptionHandler::SignalHandler(int, siginfo_t*, void*) + 0x74
[20200705 2:38:11.701 UTC] [ozw.daemon] [warning]: #4  0x00000000766c88e1 sp=0x0000000000d1b5d8 __default_sa_restorer + 0x10
[20200705 2:38:11.705 UTC] [ozw.daemon] [warning]: #5  0x00000000766b8d16 sp=0x000000007edaac80 gnu_get_libc_version + 0x119
[20200705 2:38:11.707 UTC] [ozw.daemon] [warning]: #6  0x00000000766c7bf7 sp=0x000000007edaac88 gsignal + 0x7a
[20200705 2:38:11.708 UTC] [ozw.daemon] [warning]: dumpCallback Succeeded:  true  at  /opt/ozw/config/crashes//ef48b4ac-6023-4c52-06d31985-94f18982.dmp
[20200705 2:38:11.709 UTC] [ozw.daemon] [warning]: Uploading MiniDump to  https://sentry.io/api/1868130/minidump/?sentry_key=e086ba93030843199aab391947d205da
Failed to open curl lib from binary, use libcurl.so instead
[20200705 2:38:12.563 UTC] [ozw.daemon] [warning]: Uploaded Crash minidump With ID:  6470b606-c49b-4c4f-b489-a88eff424281
codeblue11 commented 3 years ago

I am struggling with this same issue. Very frustrating. I even got the door sensor working correctly for a short period of time but eventually it ends up crashing OZW and taking down my whole zwave network

HassOS 4.17 Home Assistant 0.118.4 OpenZWave beta add-on version 0.7.1 GoControl CECOMINOD016164 HUSBZB-1 USB Hub DOME Z-WAVE PLUS DOOR/WINDOW SENSOR PRO DMDP1


[20201202 11:51:51.323 EST] [ozw.library] [info]: Info - Node: 0 NONCES: 0xef, 0x05, 0xd5, 0xfd, 0xe7, 0xb4, 0xa2, 0x2e 
[20201202 11:51:51.323 EST] [ozw.library] [info]: Info - Node: 0 NONCES: 0x5e, 0xa9, 0x76, 0xf9, 0x8f, 0x6e, 0xda, 0xa7 
[20201202 11:51:51.323 EST] [ozw.library] [info]: Info - Node: 0 NONCES: 0xe2, 0x12, 0x3e, 0xd4, 0xff, 0xbc, 0xb6, 0x8d 
[20201202 11:51:51.323 EST] [ozw.library] [info]: Info - Node: 13 Sending (Send) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x0d, 0x0a, 0x98, 0x80, 0x4a, 0x16, 0x0b, 0x76, 0x03, 0x18, 0x08, 0x84, 0x05, 0x01, 0x50: 
[20201202 11:51:51.331 EST] [ozw.library] [debug]: Detail - Node: 13   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201202 11:51:51.331 EST] [ozw.library] [debug]: Detail - Node: 13   ZW_SEND_DATA delivered to Z-Wave stack 
[20201202 11:51:51.458 EST] [ozw.library] [debug]: Detail - Node: 13   Received: 0x01, 0x05, 0x00, 0x13, 0x01, 0x00, 0xe8 
[20201202 11:51:51.458 EST] [ozw.library] [debug]: Detail - Node: 13   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01) 
[20201202 11:51:51.458 EST] [ozw.library] [info]: Info - Node: 13 Request RTT 560 Average Request RTT 431 
[20201202 11:51:51.538 EST] [ozw.library] [debug]: Detail - Node: 13   Received: 0x01, 0x1e, 0x00, 0x04, 0x00, 0x0d, 0x18, 0x98, 0x81, 0x77, 0x57, 0x59, 0x66, 0xfc, 0xda, 0x98, 0xa6, 0x95, 0xe1, 0xcf, 0xe5, 0xc2, 0x4a, 0x39, 0x0b, 0xcc, 0x48, 0x39, 0x58, 0xca, 0xc2, 0xe7 
[20201202 11:51:51.538 EST] [ozw.library] [info]: Info - Node: 0 Raw: 0x98, 0x81, 0x77, 0x57, 0x59, 0x66, 0xfc, 0xda, 0x98, 0xa6, 0x95, 0xe1, 0xcf, 0xe5, 0xc2, 0x4a, 0x39, 0x0b, 0xcc, 0x48, 0x39, 0x58, 0xca, 0xc2, 0xe7 
[20201202 11:51:51.538 EST] [ozw.library] [debug]: Detail - Node: 13 Decrypted Packet: 0x00, 0x31, 0x06, 0x01, 0x00 
[20201202 11:51:51.539 EST] [ozw.library] [info]: Info - Node: 13 Response RTT 641 Average Response RTT 624 
[20201202 11:51:51.539 EST] [ozw.library] [warning]: Warning - Node: 0 SensorMultiLevelCCTypes::GetSensorUnit - Unknown SensorScale 255 
[20201202 11:51:51.539 EST] [ozw.library] [info]: Info - Node: 13 Setting SensorMultiLevel Default Scale to:  (-1) 
[20201202 11:51:51.539 EST] [ozw.library] [warning]: Warning - Node: 0 SensorMultiLevelCCTypes::GetSensorUnit - Unknown SensorScale 255 
[20201202 11:51:51.539 EST] [ozw.library] [warning]: Warning - Node: 0 SensorMultiLevelCCTypes::GetSensorUnit - Unknown SensorScale 255 
[20201202 11:51:51.539 EST] [ozw.library] [warning]: Warning - Node: 0 Attempt to get a Invalid Label  from ValueList HomeID: 0xde403663, ValueID: (Id 0x010000000dcc4014, NodeID 13, Genre system, CC 0x31, Instance 1, Index 256, Type list) 
[20201202 11:51:51.539 EST] [ozw.library] [warning]: Warning - Node: 0 Attempt to Set a Invalid Label  for ValueList in OnValueRefreshed HomeID: 0xde403663, ValueID: (Id 0x010000000dcc4014, NodeID 13, Genre system, CC 0x31, Instance 1, Index 256, Type list) 
[20201202 11:51:51.539 EST] [ozw.library] [debug]: Detail - Node: 13   Expected reply and command class was received 
[20201202 11:51:51.539 EST] [ozw.library] [debug]: Detail - Node: 13   Message transaction complete 
[20201202 11:51:51.539 EST] [ozw.library] [debug]: Detail - Node: 13 Removing current message 
[20201202 11:51:51.539 EST] [ozw.library] [debug]: Detail - Node: 13 Notification: ValueAdded CC: COMMAND_CLASS_SENSOR_MULTILEVEL Instance: 1 Index: 1 
[20201202 11:51:51.540 EST] [ozw.library] [debug]: Detail - Node: 13 Notification: ValueAdded CC: COMMAND_CLASS_SENSOR_MULTILEVEL Instance: 1 Index: 256 
[20201202 11:51:51.540 EST] [ozw.notifications] [debug]: Notification pvt_valueAdded: 281475199811602 Thread:  0x7f8f96be38 
[20201202 11:51:51.540 EST] [ozw.library] [info]: Info - Node: 13 Sending (Query) message (Callback ID=0x4d, Expected Reply=0x04) - ZWavePlusInfoCmd_Get (Node=13): 0x01, 0x09, 0x00, 0x13, 0x0d, 0x02, 0x5e, 0x01, 0x25, 0x4d, 0xdd 
[20201202 11:51:51.540 EST] [ozw.library] [info]: Info - Node: 13 Encrypted Flag is 0 
[20201202 11:51:51.546 EST] [ozw.library] [debug]: Detail - Node: 13   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 
[20201202 11:51:51.546 EST] [ozw.library] [debug]: Detail - Node: 13   ZW_SEND_DATA delivered to Z-Wave stack 
[20201202 11:51:51.558 EST] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 281475199811602 
[20201202 11:51:51.559 EST] [ozw.notifications] [debug]: Notification pvt_valueAdded: 72057594269417492 Thread:  0x7f8f96be38 
terminate called after throwing an instance of 'std::out_of_range'
terminate called recursively
[20201202 11:51:51.571 EST] [ozw.daemon] [warning]: ============================= 
[20201202 11:51:51.571 EST] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace: 
[20201202 11:51:51.571 EST] [ozw.daemon] [warning]: ============================= 
[20201202 11:51:51.577 EST] [ozw.daemon] [warning]: #1  0x0000007f8f909a00 sp=0x0000007fc354f5c0 __setjmp + 0x38 
[20201202 11:51:51.582 EST] [ozw.daemon] [warning]: #2  0x0000007f8f909a88 sp=0x0000007fc3550820 sigsetjmp + 0x80 
[20201202 11:51:51.583 EST] [ozw.daemon] [warning]: #3  0x0000007f8f909c1c sp=0x0000007fc3550820 raise + 0x40 
[20201202 11:51:51.583 EST] [default] [warning]: Exiting.... 
[20201202 11:51:51.614 EST] [ozw.manager] [warning]: WebSocket Client Error  QAbstractSocket::RemoteHostClosedError 
[20201202 11:51:51.614 EST] [ozw.manager] [debug]: QTOZWManager Ready! 
[20201202 11:51:51.614 EST] [ozw.manager] [debug]: QTOZWLog Ready! 
[20201202 11:51:51.614 EST] [ozw.manager] [debug]: QTOZWOptions Ready! 
[20201202 11:51:51.614 EST] [ozwadmin] [debug]: Remote Connection Status:  QTOZWManager::ConnectionErrorState QAbstractSocket::RemoteHostClosedError 
1606927911: Socket error on client qt-openzwave-1, disconnecting.
In exit
Terminating child 506
Process Process-2:
Traceback (most recent call last):
  File "/usr/lib/python3.8/multiprocessing/process.py", line 315, in _bootstrap
    self.run()
  File "/usr/lib/python3.8/multiprocessing/process.py", line 108, in run
    self._target(*self._args, **self._kwargs)
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 691, in top_new_client
    client = self.do_handshake(startsock, address)
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 619, in do_handshake
    self.RequestHandlerClass(retsock, address, self)
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 99, in __init__
    SimpleHTTPRequestHandler.__init__(self, req, addr, server)
  File "/usr/lib/python3.8/http/server.py", line 647, in __init__
    super().__init__(*args, **kwargs)
  File "/usr/lib/python3.8/socketserver.py", line 720, in __init__
    self.handle()
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 315, in handle
    SimpleHTTPRequestHandler.handle(self)
  File "/usr/lib/python3.8/http/server.py", line 427, in handle
    self.handle_one_request()
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketserver.py", line 47, in handle_one_request
    super(WebSocketRequestHandlerMixIn, self).handle_one_request()
  File "/usr/lib/python3.8/http/server.py", line 415, in handle_one_request
    method()
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketserver.py", line 60, in _websocket_do_GET
    self.handle_upgrade()
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 221, in handle_upgrade
    WebSocketRequestHandlerMixIn.handle_upgrade(self)
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketserver.py", line 87, in handle_upgrade
    self.handle_websocket()
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 259, in handle_websocket
    self.new_websocket_client()
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketproxy.py", line 134, in new_websocket_client
    self.do_proxy(tsock)
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketproxy.py", line 209, in do_proxy
    ins, outs, excepts = select.select(rlist, wlist, [], 1)
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 683, in do_SIGTERM
    self.terminate()
  File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 656, in terminate
    raise self.Terminate()
websockify.websockifyserver.WebSockifyServer.Terminate
[cont-finish.d] executing container finish scripts...
[cont-finish.d] mqtt.sh: executing... 
1606927911: mosquitto version 1.6.8 terminating
1606927911: Saving in-memory database to /data/mosquitto.db.
[11:51:52] 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.```
JoakimLindbom commented 3 years ago

I'm having the same problem, or a variation of it. HassOS: 4.17 Home Assistant 0.118.5 OpenZWave: 0.7.1 Raspberry Pi 4 RaZberry Controller 2016 ZWave+
Secure SEC_SRT321 Thermostat

I have a small running zwave network, where I have one observation: My controller supports Zwave Plus, but in Capabilities, ZWave Plus is not checked for the COntroller (but it is for other devices). Not sure if this is an issue.

When including aThermostat, the OpenZwav UI crashes, and the following is in the log:

[20201206 16:26:22.944 CET] [ozw.library] [debug]: Detail - Node: 14 ZW_SEND_DATA delivered to Z-Wave stack [20201206 16:26:22.945 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 844425179758611 [20201206 16:26:22.945 CET] [ozw.notifications] [debug]: Notification pvt_valueAdded: 1125900156469267 Thread: 0xb6f81558 [20201206 16:26:22.947 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 1125900156469267 [20201206 16:26:22.947 CET] [ozw.notifications] [debug]: Notification pvt_valueAdded: 249626643 Thread: 0xb6f81558 [20201206 16:26:22.949 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 249626643 [20201206 16:26:22.949 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged: 249626643 Thread: 0xb6f81558 [20201206 16:26:22.950 CET] [ozw.notifications] [debug]: Notification pvt_valueAdded: 249659415 Thread: 0xb6f81558 [20201206 16:26:22.952 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 249659415 [20201206 16:26:22.952 CET] [ozw.notifications] [debug]: Notification pvt_valueAdded: 281475226370071 Thread: 0xb6f81558 [20201206 16:26:22.954 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 281475226370071 [20201206 16:26:22.955 CET] [ozw.notifications] [debug]: Notification pvt_valueAdded: 562950203080727 Thread: 0xb6f81558 [20201206 16:26:22.957 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 562950203080727 [20201206 16:26:22.958 CET] [ozw.notifications] [debug]: Notification pvt_valueAdded: 281475216588818 Thread: 0xb6f81558 [20201206 16:26:22.960 CET] [ozw.mqtt.publisher] [debug]: Publishing Event valueAdded: 281475216588818 [20201206 16:26:22.960 CET] [ozw.notifications] [debug]: Notification pvt_valueChanged: 281475216588818 Thread: 0xb6f81558 [20201206 16:26:22.965 CET] [ozw.library] [debug]: Detail - Node: 14 Received: 0x01, 0x07, 0x00, 0x13, 0x54, 0x00, 0x00, 0x03, 0xbc [20201206 16:26:22.965 CET] [ozw.library] [debug]: Detail - Node: 14 ZW_SEND_DATA Request with callback ID 0x54 received (expected 0x54) [20201206 16:26:22.966 CET] [ozw.library] [info]: Info - Node: 14 Request RTT 28 Average Request RTT 26 [20201206 16:26:22.966 CET] [ozw.library] [debug]: Detail - Node: 14 Expected callbackId was received [20201206 16:26:22.976 CET] [ozw.library] [debug]: Detail - Node: 14 Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x0e, 0x03, 0x40, 0x05, 0x02, 0xb8 [20201206 16:26:22.976 CET] [ozw.library] [info]: Info - Node: 14 Response RTT 38 Average Response RTT 38 [20201206 16:26:22.976 CET] [ozw.library] [info]: Info - Node: 14 Received supported thermostat modes [20201206 16:26:22.977 CET] [ozw.library] [info]: Info - Node: 14 Added mode: Heat [20201206 16:26:22.978 CET] [ozw.library] [debug]: Detail - Node: 14 Expected reply and command class was received [20201206 16:26:22.978 CET] [ozw.library] [debug]: Detail - Node: 14 Message transaction complete [20201206 16:26:22.978 CET] [ozw.library] [debug]: Detail - Node: 14 Removing current message [20201206 16:26:22.979 CET] [ozw.library] [debug]: Detail - Node: 14 Notification: ValueAdded CC: COMMAND_CLASS_THERMOSTAT_MODE Instance: 1 Index: 0 [20201206 16:26:22.979 CET] [ozw.library] [info]: Info - Node: 14 Sending (Query) message (Callback ID=0x55, Expected Reply=0x04) - ThermostatSetpointCmd_SupportedGet (Node=14): 0x01, 0x09, 0x00, 0x13, 0x0e, 0x02, 0x43, 0x04, 0x25, 0x55, 0xde [20201206 16:26:22.979 CET] [ozw.library] [info]: Info - Node: 14 Encrypted Flag is 0 [20201206 16:26:22.981 CET] [ozw.notifications] [debug]: Notification pvt_valueAdded: 240123924 Thread: 0xb6f81558 terminate called after throwing an instance of 'std::out_of_range' terminate called recursively

[20201206 16:26:22.986 CET] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace:

[20201206 16:26:22.986 CET] [ozw.library] [debug]: Detail - Node: 14 Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8 [20201206 16:26:22.987 CET] [ozw.library] [debug]: Detail - Node: 14 ZW_SEND_DATA delivered to Z-Wave stack [20201206 16:26:22.989 CET] [ozw.daemon] [warning]: #1 0x00000000b6f3859b sp=0x00000000be8ecc98 pselect + 0x3c [20201206 16:26:22.990 CET] [ozw.daemon] [warning]: #2 0x00000000b6f385da sp=0x00000000be8ecf90 sigsetjmp + 0x33

1607268383: Socket error on client qt-openzwave-1, disconnecting. [20201206 16:26:23.006 CET] [ozw.manager] [warning]: WebSocket Client Error QAbstractSocket::RemoteHostClosedError [20201206 16:26:23.006 CET] [ozw.manager] [debug]: QTOZWManager Ready! [20201206 16:26:23.006 CET] [ozw.manager] [debug]: QTOZWLog Ready! [20201206 16:26:23.006 CET] [ozw.manager] [debug]: QTOZWOptions Ready! [20201206 16:26:23.006 CET] [ozwadmin] [debug]: Remote Connection Status: QTOZWManager::ConnectionErrorState QAbstractSocket::RemoteHostClosedError In exit Terminating child 503 Process Process-1: Traceback (most recent call last): File "/usr/lib/python3.8/multiprocessing/process.py", line 315, in _bootstrap self.run() File "/usr/lib/python3.8/multiprocessing/process.py", line 108, in run self._target(*self._args, *self._kwargs) File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 691, in top_new_client client = self.do_handshake(startsock, address) File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 619, in do_handshake self.RequestHandlerClass(retsock, address, self) File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 99, in init SimpleHTTPRequestHandler.init(self, req, addr, server) File "/usr/lib/python3.8/http/server.py", line 647, in init super().init(args, **kwargs) File "/usr/lib/python3.8/socketserver.py", line 720, in init self.handle() File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 315, in handle SimpleHTTPRequestHandler.handle(self) File "/usr/lib/python3.8/http/server.py", line 429, in handle self.handle_one_request() File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketserver.py", line 47, in handle_one_request super(WebSocketRequestHandlerMixIn, self).handle_one_request() File "/usr/lib/python3.8/http/server.py", line 415, in handle_one_request method() File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketserver.py", line 60, in _websocket_do_GET self.handle_upgrade() File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 221, in handle_upgrade WebSocketRequestHandlerMixIn.handle_upgrade(self) File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketserver.py", line 87, in handle_upgrade self.handle_websocket() File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 259, in handle_websocket self.new_websocket_client() File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketproxy.py", line 134, in new_websocket_client self.do_proxy(tsock) File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websocketproxy.py", line 209, in do_proxy ins, outs, excepts = select.select(rlist, wlist, [], 1) File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 683, in do_SIGTERM self.terminate() File "/usr/lib/python3.8/site-packages/websockify-0.9.0-py3.8.egg/websockify/websockifyserver.py", line 656, in terminate raise self.Terminate() websockify.websockifyserver.WebSockifyServer.Terminate [cont-finish.d] executing container finish scripts... [cont-finish.d] mqtt.sh: executing... 1607268383: mosquitto version 1.6.8 terminating 1607268383: Saving in-memory database to /data/mosquitto.db. [16:26:23] 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.