search

OpenZWave / qt-openzwave

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

ozw.daemon crash #93

Open l7s opened 4 years ago

l7s commented 4 years ago

Hi, I am running openzwave/ozwdaemon:latest docker container on PI 4 Linux raspberrypi 4.19.118-v7l+ OS Raspbian GNU/Linux 10 (buster) along with Aeotec USB Stick GEN5.

It crashed leaving logs trace as below:

[20200603 7:56:43.996 UTC] [ozw.library] [debug]: Detail - Node: 8   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
[20200603 7:56:43.996 UTC] [ozw.library] [debug]: Detail - Node: 8   ZW_SEND_DATA delivered to Z-Wave stack
[20200603 7:56:43.997 UTC] [ozw.library] [debug]: Detail - Node: 8   Received: 0x01, 0x07, 0x00, 0x13, 0x40, 0x00, 0x00, 0x05, 0xae
[20200603 7:56:43.997 UTC] [ozw.library] [debug]: Detail - Node: 8   ZW_SEND_DATA Request with callback ID 0x40 received (expected 0x40)
[20200603 7:56:43.997 UTC] [ozw.library] [info]: Info - Node: 8 Request RTT 2007 Average Request RTT 1071
[20200603 7:56:43.997 UTC] [ozw.library] [debug]: Detail - Node: 8   Expected callbackId was received
[20200603 7:56:43.999 UTC] [ozw.library] [debug]: Detail - Node: 8   Received: 0x01, 0x1d, 0x00, 0x04, 0x00, 0x08, 0x17, 0x72, 0x07, 0x01, 0x33, 0x0c, 0x00, 0x01, 0x00, 0x01, 0x01, 0x09, 0x04, 0x07, 0x00, 0x00, 0x00, 0x08, 0x02, 0x04, 0x00, 0x00, 0x00, 0x00, 0xb7
[20200603 7:56:44.000 UTC] [ozw.library] [debug]: Detail - Node: 0 
[20200603 7:56:44.000 UTC] [ozw.library] [info]: Info - Node: 8 Response RTT 2011 Average Response RTT 1145
[20200603 7:56:44.098 UTC] [ozw.daemon] [warning]: =============================
[20200603 7:56:44.098 UTC] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace:
[20200603 7:56:44.099 UTC] [ozw.daemon] [warning]: =============================
[20200603 7:56:44.101 UTC] [ozw.daemon] [warning]: #1  0x000000000051d6f1 sp=0x00000000b44a70a0 dumpCallback(google_breakpad::MinidumpDescriptor const&, void*, bool) + 0x18
[20200603 7:56:44.101 UTC] [ozw.daemon] [warning]: #2  0x000000000052d569 sp=0x00000000b44a7260 google_breakpad::ExceptionHandler::GenerateDump(google_breakpad::ExceptionHandler::CrashContext*) + 0x198
[20200603 7:56:44.102 UTC] [ozw.daemon] [warning]: #3  0x000000000052d825 sp=0x00000000b44a72a8 google_breakpad::ExceptionHandler::SignalHandler(int, siginfo_t*, void*) + 0x74
[20200603 7:56:44.102 UTC] [ozw.daemon] [warning]: #4  0x00000000b6697951 sp=0x00000000b44a7358 __default_sa_restorer + 0x10
[20200603 7:56:44.107 UTC] [ozw.daemon] [warning]: #5  0x00000000b65d5c8a sp=0x00000000b44a76d0 OpenZWave::Internal::VC::Value::VerifyRefreshedValue(void*, void*, void*, OpenZWave::ValueID::ValueType, int, int, int) + 0x5
[20200603 7:56:44.108 UTC] [ozw.daemon] [warning]: dumpCallback Succeeded:  true  at  /opt/ozw/config/crashes//df7b142a-755e-456f-ab78c39e-67a30738.dmp
[20200603 7:56:44.108 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
[20200603 7:56:45.739 UTC] [ozw.daemon] [warning]: Uploaded Crash minidump With ID:  12c87364-e913-4672-9154-6141e726fa71

I am guessing you already have access to dump file on sentry.io but please let me know if I can help in any way to investigate this further.

Regards, Chris

yllar commented 4 years ago

I'm having same issue. raspberry pi 2, buster, Aeotec USB Stick GEN5. openzwave/ozwdaemon latest 0ef0467be8d8 2 hours ago 301MB 

qt-openzwave    | [20200615 20:45:33.328 UTC] [ozw.library] [info]: Info - Node: 7 Request RTT 88 Average Request RTT 126
qt-openzwave    | [20200615 20:45:33.329 UTC] [ozw.library] [debug]: Detail - Node: 7   Expected callbackId was received
qt-openzwave    | [20200615 20:45:33.416 UTC] [ozw.library] [debug]: Detail - Node: 7   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x07, 0x0c, 0x72, 0x07, 0x00, 0x28, 0xb7, 0x12, 0xb8, 0x4f, 0x3f, 0xb1, 0x64, 0xc5, 0xc2
qt-openzwave    | [20200615 20:45:33.417 UTC] [ozw.library] [info]: Info - Node: 7 Response RTT 176 Average Response RTT 249
qt-openzwave    | [20200615 20:45:33.528 UTC] [ozw.daemon] [warning]: =============================
qt-openzwave    | [20200615 20:45:33.529 UTC] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace:
qt-openzwave    | [20200615 20:45:33.529 UTC] [ozw.daemon] [warning]: =============================
qt-openzwave    | [20200615 20:45:33.534 UTC] [ozw.daemon] [warning]: #1  0x00000000004a3811 sp=0x00000000742d20a0 dumpCallback(google_breakpad::MinidumpDescriptor const&, void*, bool) + 0x18
qt-openzwave    | [20200615 20:45:33.536 UTC] [ozw.daemon] [warning]: #2  0x00000000004b3d6d sp=0x00000000742d2260 google_breakpad::ExceptionHandler::GenerateDump(google_breakpad::ExceptionHandler::CrashContext*) + 0x198
qt-openzwave    | [20200615 20:45:33.538 UTC] [ozw.daemon] [warning]: #3  0x00000000004b4029 sp=0x00000000742d22a8 google_breakpad::ExceptionHandler::SignalHandler(int, siginfo_t*, void*) + 0x74
qt-openzwave    | [20200615 20:45:33.540 UTC] [ozw.daemon] [warning]: #4  0x000000007660e8e1 sp=0x00000000742d2358 __default_sa_restorer + 0x10
qt-openzwave    | [20200615 20:45:33.542 UTC] [ozw.daemon] [warning]: #5  0x000000007654cc8a sp=0x00000000742d26d0 OpenZWave::Internal::VC::Value::VerifyRefreshedValue(void*, void*, void*, OpenZWave::ValueID::ValueType, int, int, int) + 0x5
qt-openzwave    | [20200615 20:45:33.543 UTC] [ozw.daemon] [warning]: dumpCallback Succeeded:  true  at  /opt/ozw/config/crashes//274646ae-f1ba-42ac-f886ec93-c7a87715.dmp
qt-openzwave    | [20200615 20:45:33.544 UTC] [ozw.daemon] [warning]: Uploading MiniDump to  https://sentry.io/api/1868130/minidump/?sentry_key=e086ba93030843199aab391947d205da
qt-openzwave    | Failed to open curl lib from binary, use libcurl.so instead
qt-openzwave    | [20200615 20:45:34.590 UTC] [ozw.daemon] [warning]: Uploaded Crash minidump With ID:  05f379a6-b1c5-49d6-82ab-5e325056634f
sharondagan commented 4 years ago

Same here - crash on VerifyRefreshedValue tried on both intel NUC and RPi 3 using Aeotec USB Stick GEN5

linuxius commented 4 years ago

I am having the same issue using the Aeotec Stick. In my case I am using the new OpenZWave add-on of Home Assistant on a KVM virtual machine.

linuxius commented 4 years ago

Update: I changed to the ozwdaemon container from docker hub instead of the hassio version. This one has been running stable for several days.

hefla commented 3 years ago

Same issue using the latest Home assistant addon (0.5.2) on a RPI 3B and a Aeotec USB Stick Gen 5.

`[20201019 2:50:05.313 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted [20201019 2:50:05.313 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.318 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted [20201019 2:50:05.318 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.324 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted [20201019 2:50:05.324 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.330 CEST] [ozw.library] [info]: Info - Node: 0 Saving Cache [20201019 2:50:05.331 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.337 CEST] [ozw.library] [info]: Info - Node: 1 Cache Save for Node 1 as its QueryStage_CacheLoad [20201019 2:50:05.337 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.347 CEST] [ozw.library] [info]: Info - Node: 12 Cache Save for Node 12 as its QueryStage_CacheLoad [20201019 2:50:05.347 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.357 CEST] [ozw.library] [info]: Info - Node: 13 Cache Save for Node 13 as its QueryStage_CacheLoad [20201019 2:50:05.357 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.367 CEST] [ozw.library] [info]: Info - Node: 14 Cache Save for Node 14 as its QueryStage_CacheLoad [20201019 2:50:05.367 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.375 CEST] [ozw.library] [info]: Info - Node: 15 Cache Save for Node 15 as its QueryStage_CacheLoad [20201019 2:50:05.375 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.384 CEST] [ozw.library] [info]: Info - Node: 16 Cache Save for Node 16 as its QueryStage_CacheLoad [20201019 2:50:05.385 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.392 CEST] [ozw.library] [info]: Info - Node: 17 Cache Save for Node 17 as its QueryStage_CacheLoad [20201019 2:50:05.392 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.402 CEST] [ozw.library] [info]: Info - Node: 18 Cache Save for Node 18 as its QueryStage_CacheLoad [20201019 2:50:05.402 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.412 CEST] [ozw.library] [info]: Info - Node: 19 Cache Save for Node 19 as its QueryStage_CacheLoad [20201019 2:50:05.412 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.422 CEST] [ozw.library] [info]: Info - Node: 20 Cache Save for Node 20 as its QueryStage_CacheLoad [20201019 2:50:05.422 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.432 CEST] [ozw.library] [info]: Info - Node: 21 Cache Save for Node 21 as its QueryStage_CacheLoad [20201019 2:50:05.432 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.441 CEST] [ozw.library] [info]: Info - Node: 22 Cache Save for Node 22 as its QueryStage_CacheLoad [20201019 2:50:05.442 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.451 CEST] [ozw.library] [info]: Info - Node: 23 Cache Save for Node 23 as its QueryStage_CacheLoad [20201019 2:50:05.452 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.461 CEST] [ozw.library] [info]: Info - Node: 24 Cache Save for Node 24 as its QueryStage_CacheLoad [20201019 2:50:05.461 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.471 CEST] [ozw.library] [info]: Info - Node: 25 Cache Save for Node 25 as its QueryStage_CacheLoad [20201019 2:50:05.471 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.480 CEST] [ozw.library] [info]: Info - Node: 26 Cache Save for Node 26 as its QueryStage_CacheLoad [20201019 2:50:05.480 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.490 CEST] [ozw.library] [info]: Info - Node: 27 Cache Save for Node 27 as its QueryStage_CacheLoad [20201019 2:50:05.491 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.501 CEST] [ozw.library] [info]: Info - Node: 28 Cache Save for Node 28 as its QueryStage_CacheLoad [20201019 2:50:05.501 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.512 CEST] [ozw.library] [info]: Info - Node: 29 Cache Save for Node 29 as its QueryStage_CacheLoad [20201019 2:50:05.512 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.520 CEST] [ozw.library] [info]: Info - Node: 30 Cache Save for Node 30 as its QueryStage_CacheLoad [20201019 2:50:05.520 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.529 CEST] [ozw.library] [info]: Info - Node: 31 Cache Save for Node 31 as its QueryStage_CacheLoad [20201019 2:50:05.529 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.540 CEST] [ozw.library] [info]: Info - Node: 32 Cache Save for Node 32 as its QueryStage_CacheLoad [20201019 2:50:05.540 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.853 CEST] [ozw.library] [debug]: Detail - Node: 11 AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1 [20201019 2:50:05.853 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.859 CEST] [ozw.library] [debug]: Detail - Node: 11 QueryStage_ProtocolInfo [20201019 2:50:05.860 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.865 CEST] [ozw.library] [debug]: Detail - Node: 11 Queuing (Query) Get Node Protocol Info (Node=11): 0x01, 0x04, 0x00, 0x41, 0x0b, 0xb1 [20201019 2:50:05.865 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.871 CEST] [ozw.library] [debug]: Detail - Node: 11 Queuing (Query) Query Stage Complete (ProtocolInfo) [20201019 2:50:05.871 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.877 CEST] [ozw.library] [info]: Info - Node: 11 Initializing Node. New Node: false (false) [20201019 2:50:05.877 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.930 CEST] [ozw.library] [warning]: Warning - Node: 11 Node::GetValue - Couldn't find ValueID in Store: HomeID: 0xde5989ea, ValueID: (Id 0x000900000b9c0016, NodeID 11, Genre config, CC 0x70, Instance 1, Index 9, Type short) [20201019 2:50:05.930 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.936 CEST] [ozw.library] [critical]: Error - Node: 0 MutexImpl::UnLock failed with error: 2 (1)

[20201019 2:50:05.936 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.942 CEST] [ozw.library] [warning]: Warning - Node: 0 Exception: Manager.cpp:1570 - 101 - Invalid ValueID passed to GetValueLabel [20201019 2:50:05.942 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.948 CEST] [ozw.library] [debug]: Detail - Node: 11 Notification: ValueRemoved CC: COMMAND_CLASS_BASIC Instance: 1 Index: 0 [20201019 2:50:05.948 CEST] [ozw.logging] [debug]: popping Log Mesages terminate called after throwing an instance of 'OpenZWave::OZWException' terminate called recursively

[20201019 2:50:05.952 CEST] [ozw.daemon] [warning]: CRASH!!! - Dumping Backtrace:

[20201019 2:50:05.955 CEST] [ozw.library] [debug]: Detail - Node: 11 Notification: ValueRemoved CC: COMMAND_CLASS_SENSOR_BINARY Instance: 1 Index: 0 [20201019 2:50:05.955 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.956 CEST] [ozw.daemon] [warning]: #1 0x0000000076ebe57d sp=0x000000007ebfcff8 pselect + 0x3c [20201019 2:50:05.958 CEST] [ozw.daemon] [warning]: #2 0x0000000076ebe5bc sp=0x000000007ebfd2f0 sigsetjmp + 0x33

[20201019 2:50:05.962 CEST] [ozw.library] [debug]: Detail - Node: 11 Notification: ValueRemoved CC: COMMAND_CLASS_ZWAVEPLUS_INFO Instance: 1 Index: 0 [20201019 2:50:05.962 CEST] [ozw.logging] [debug]: popping Log Mesages [20201019 2:50:05.997 CEST] [ozw.manager] [warning]: WebSocket Client Error QAbstractSocket::RemoteHostClosedError [20201019 2:50:05.997 CEST] [ozw.manager] [debug]: QTOZWManager Ready! [20201019 2:50:05.997 CEST] [ozw.manager] [debug]: QTOZWLog Ready! 1603068605: Socket error on client qt-openzwave-1, disconnecting. [20201019 2:50:05.997 CEST] [ozw.manager] [debug]: QTOZWOptions Ready! [20201019 2:50:05.998 CEST] [ozwadmin] [debug]: Remote Connection Status: QTOZWManager::ConnectionErrorState QAbstractSocket::RemoteHostClosedError In exit [cont-finish.d] executing container finish scripts... [cont-finish.d] mqtt.sh: executing... 1603068606: mosquitto version 1.6.8 terminating 1603068606: Saving in-memory database to /data/mosquitto.db. [02:50:06] 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.`