OpenZWave not working after update

Tags: #<Tag:0x00007f7398f5b1e0>

@freshcoast @ToxicSmasher Checking my MQTT server log, I don’t even see and any core-zwave.main-mqtt attempts. I even in the MQTT integration set it to 1883 and disabled the password requirements on the server. Is it possible the Supervisor installed OpenZwave container can’t connect to a remote MQTT server?

The Zwave Addon talks to an embedded mqtt broker, which is bridged to the MQTT Addon broker.

I think he’s implying he’s not using the MQTT Addon.

Correct, I have another broker not in a container on my HA box, I thought I read that was supported, but I may have misunderstood? I’ve been running an external broker for years, before I had HA so had no reason to move it into HA as a container when I started using HA. I use the MQTT integration to connect to the external broker, maybe that’s where I am failing here?

I don’t use the addon, but at this moment the docs say:

This add-on currently requires to have the Mosquitto add-on installed. Please make sure to install and set up that add-on before continuing.

I had initially started with Get OpenZWave Beta working then looks like very much misread the doc after and took that as the MQTT Integration == the MQTT Addon when I read that, likely my issue, thank you @freshcoast!

You could try installing the Mosquitto Addon, setting HA to use that, and bridge it with your external broker. I think someone posted an example in one of the other OZW threads. Otherwise, standalone Docker is the way to go.

Exactly what I was just thinking, I’ve connected brokers before as bridge to TTN and a couple others, thank you for the help!

This is so strange.

Now in the morning the addon started up without any problems. And i have not made a single change. All settings are as they where when i went to sleep.

This would lead me to believe that there is a scheduled task, whether it’s time based or based on some threshold being met, I don’t know. But some kind of cleanup / garbage collection probably fixed things this time around. It has for me as well. Unfortunately you can’t rely on this fixing your network daily.

I am on the same boat with you guys. Had the OpenZWave Addon running for a good while (even with 0.112.4), but had to reboot my Pi box (due to electricity work) and since the restart the addon has refused to start. Error messages are exactly the same as reported above for both OZW addon as well as the MQTT broker.

Has anyone found a reliable workaround? I can see the now-pulled 0.112.5 contains MQTT fixes, but I would assume these are not related (as this does not seem to be related to Core).

I’m in the same boat the where openzwave addon would constantly quit immediately after starting up. I believe it’s because it’s running a internal mqtt broker and it’s not cleaning the topics up itself. I don’t know enough how to manipulate the internal mqtt broker inside the container.

I just modified the docker-compose file from get opzwave beta working and ran the openzwave container and not use the addon. I just switched to a supervised build of ubuntu so I can run my own docker apps outside of haasio I was using previously

So far so good, openzwave 1.6 container seems to be working well, and home assistant sees all my zwave devices without any issues. I do have to download the ozw-admin on my windows box and run it separately but it actually seems to work better than the vnc plugin.

2 Likes

Unfortunately I might have to go this route as well; I’m using a supervised install on ubuntu and when I use the openzwave and mosquitto addons openzwave will start the first time after install but after I restart it even once it won’t start again unless I uninstall both the openzwave and mosquitto addons and start over. I see Socket error on client core-zwave.main-mqtt from the mosquitto logs and s6-svwait: fatal: supervisor died from the openzwave log (amonst other things). I slapped my openzwave logs below in case someone has another thought on what’s going on here, I knew this was beta so it has some issues; luckily I just moved and haven’t built my zwave network back (that was why I’m testing this)

[20200717 12:00:54.052 MDT] [ozw.mqtt.publisher] [warning]: "{\n    \"OpenZWave_Version\": \"1.6.1198\",\n    \"OZWDaemon_Version\": \"0.1.0\",\n    \"QTOpenZWave_Version\": \"1.2.0\",\n    \"QT_Version\": \"5.12.5\",\n    \"Status\": \"driverAllNodesQueriedSomeDead\",\n    \"TimeStamp\": 1595008562,\n    \"ManufacturerSpecificDBReady\": true,\n    \"homeID\": 3597181125,\n    \"getControllerNodeId\": 1,\n    \"getSUCNodeId\": 0,\n    \"isPrimaryController\": false,\n    \"isBridgeController\": false,\n    \"hasExtendedTXStatistics\": false,\n    \"getControllerLibraryVersion\": \"Z-Wave 4.54\",\n    \"getControllerLibraryType\": \"Static Controller\",\n    \"getControllerPath\": \"/dev/serial/by-id/usb-0658_0200-if00\"\n}" 
[20200717 12:00:54.053 MDT] [ozw.library] [debug]: Detail - Node: 0 Notification: DriverRemoved 
[20200717 12:00:54.053 MDT] [ozw.notifications] [debug]: Notification pvt_driverRemoved  0 Thread:  0x7fe126e37d48 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Publishing Event stopped 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Publishing Event driverRemoved: 0 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/" 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/" 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/" 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/" 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/112/" 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/112/" 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/22799473140563988/" 
[20200717 12:00:54.053 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/22799473140563988/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/61924494903345172/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/61924494903345172/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/68116944390979604/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/68116944390979604/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/68398419367690259/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/68398419367690259/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/70931694158086164/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/70931694158086164/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/71776119088218131/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/112/value/71776119088218131/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/32/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/32/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/32/value/17301521/" 
[20200717 12:00:54.054 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/32/value/17301521/" 
[20200717 12:00:54.055 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/32/value/281474994012177/" 
[20200717 12:00:54.055 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/32/value/281474994012177/" 
[20200717 12:00:54.055 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/32/value/562949970722835/" 
[20200717 12:00:54.055 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/32/value/562949970722835/" 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 *************************************************************************** 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 *********************  Cumulative Network Statistics  ********************* 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 *** General 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 Driver run time: . .  . 0 days, 0 hours, 0 minutes 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 Frames processed: . . . . . . . . . . . . . . . . . . . . 0 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 Total messages successfully received: . . . . . . . . . . 0 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 Total Messages successfully sent: . . . . . . . . . . . . 0 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 ACKs received from controller:  . . . . . . . . . . . . . 0 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 *** Errors 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 Unsolicited messages received while waiting for ACK:  . . 0 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 Reads aborted due to timeouts:  . . . . . . . . . . . . . 0 
[20200717 12:00:54.055 MDT] [ozw.library] [debug]: Always - Node: 0 Bad checksum errors:  . . . . . . . . . . . . . . . . . . 0 
[20200717 12:00:54.056 MDT] [ozw.library] [debug]: Always - Node: 0 CANs received from controller:  . . . . . . . . . . . . . 0 
[20200717 12:00:54.056 MDT] [ozw.library] [debug]: Always - Node: 0 NAKs received from controller:  . . . . . . . . . . . . . 0 
[20200717 12:00:54.056 MDT] [ozw.library] [debug]: Always - Node: 0 Out of frame data flow errors:  . . . . . . . . . . . . . 0 
[20200717 12:00:54.056 MDT] [ozw.library] [debug]: Always - Node: 0 Messages retransmitted: . . . . . . . . . . . . . . . . . 0 
[20200717 12:00:54.056 MDT] [ozw.library] [debug]: Always - Node: 0 Messages dropped and not delivered: . . . . . . . . . . . 0 
[20200717 12:00:54.056 MDT] [ozw.library] [debug]: Always - Node: 0 *************************************************************************** 
[20200717 12:00:54.056 MDT] [ozw.library] [warning]: Warning - Node: 0 WARNING: Tried to write driver config with no home ID set 
[20200717 12:00:54.057 MDT] [ozw.library] [debug]: Detail - Node: 0 WriteMsg Wait Timeout m_currentMsg=00000000 
[20200717 12:00:54.074 MDT] [ozw.library] [info]: Info - Node: 0 Lookup for mfs.db.openzwave.com returned 109 
[20200717 12:00:54.074 MDT] [ozw.library] [info]: Info - Node: 0 Stopping DNSThread 
[12:00:54] INFO: Starting websockify...
[12:00:54] INFO: Starting ozw-admin...
QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-root'
ozwadmin: Settings Path is at  "/root/.config/OpenZWave/ozw-admin.conf"
ozwadmin: Checking  "/data/ozw/config"  for manufacturer_specific.xml
ozwadmin: Checking  "/data/ozw/config"  for options.xml
ozwadmin: DBPath:  "/data/ozw/config/"
ozwadmin: userPath:  "/data/ozw/config/"
ozwadmin: Settings Path:  "/root/.config/OpenZWave/ozw-admin.conf"
ozwadmin: Using Saved Network Cache Size: 1000
QT_MESSAGE_PATTERN: %{backtrace} is not supported by this Qt build
[20200717 12:00:54.688 MDT] [ozwadmin] [info]: OZWCore Initilized QTOpenZwave(0x7f647f63a720) QTOZWManager(0x5637892c55c0) 
[20200717 12:00:54.719 MDT] [default] [warning]: QVncServer created on port 5900 
[20200717 12:00:54.777 MDT] [ozw.library] [debug]: Debug - Node: 0 Downloads Remaining: 0 
[20200717 12:00:54.778 MDT] [ozw.library] [info]: Info - Node: 0 ManufacturerSpecificDB Initialized 
WebSocket server settings:
  - Listen on :8099
  - Web server. Web root: /usr/share/novnc
  - No SSL/TLS support (no cert file)
  - proxying from :8099 to 127.0.0.1:5900
[20200717 12:00:56.780 MDT] [ozw.library] [info]: Info - Node: 0 mgr,     Driver for controller /dev/serial/by-id/usb-0658_0200-if00 removed 
[20200717 12:00:56.780 MDT] [ozw.manager] [debug]: Driver Removed for  "/dev/serial/by-id/usb-0658_0200-if00" 
[20200717 12:00:56.780 MDT] [ozw.manager] [debug]: OZW Serial Port Closed 
[20200717 12:00:56.780 MDT] [ozw.daemon] [info]: Shutting Down  "ozwdaemon"  Version:  "0.1.0" 
[20200717 12:00:56.780 MDT] [ozw.daemon] [info]: OpenZWave Version:  "1.6.1198" 
[20200717 12:00:56.780 MDT] [ozw.daemon] [info]: QT-OpenZWave Version:  "1.2.0" 
[20200717 12:00:56.780 MDT] [ozw.daemon] [info]: QT Version:  5.12.5 
[20200717 12:00:56.781 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/114/" 
[20200717 12:00:56.781 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/114/" 
[20200717 12:00:56.781 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/114/value/31227923/" 
[20200717 12:00:56.781 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/114/value/31227923/" 
[20200717 12:00:56.782 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/114/value/281475007938579/" 
[20200717 12:00:56.782 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/114/value/281475007938579/" 
[20200717 12:00:56.782 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/1/instance/1/commandclass/114/value/562949984649235/" 
[20200717 12:00:56.782 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/1/instance/1/commandclass/114/value/562949984649235/" 
[20200717 12:00:56.782 MDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/4/" 
[20200717 12:00:56.782 MDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/4/" 
1595008856: Client qt-openzwave-1 disconnected.
In exit
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
s6-svwait: fatal: supervisor died
1595008856: mosquitto version 1.6.8 terminating
1595008856: Saving in-memory database to /data/mosquitto.db.
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.
1 Like

I have the same issue and it is driving me crazy. I get the another ozwdaemon is running error. If I restart the broker, I get the error. I followed one of the post and looked at it in MQTT Explorer. There was a status message hanging out there for Instance 1. I removed it and restart OpenZwave and every came back up.

That screenshot was after restart, but before restart there was just a 1 node and a status.

I’ve tried removing the openzwave status message in my MQTT server and it didn’t matter.
I’m also uploading my full logs. Full logs were obtained by running

docker logs addon_core_zwave > addon_core_zwave.log 2>&1

As you can see it launches an internal MQTT broker.

[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] auth.sh: executing... 
[23:04:29] INFO: e[32mAuth database existse[0m
[cont-init.d] auth.sh: exited 0.
[cont-init.d] mqtt.sh: executing... 
[23:04:29] INFO: e[32mConnect to internal MqTT servicee[0m
[cont-init.d] mqtt.sh: exited 0.
[cont-init.d] network_key.sh: executing... 
[cont-init.d] network_key.sh: exited 0.
[cont-init.d] novnc.sh: executing... 
[cont-init.d] novnc.sh: exited 0.
[cont-init.d] structure.sh: executing... 
[cont-init.d] structure.sh: exited 0.
[cont-init.d] done.
[services.d] starting services
[services.d] done.
[23:04:30] INFO: e[32mStarting internal MQTT broker...e[0m
1594965870: mosquitto version 1.6.8 starting
1594965870: Config loaded from /etc/mosquitto.conf.
1594965870: Opening ipv4 listen socket on port 1883.
1594965870: Opening ipv6 listen socket on port 1883.
1594965870: Warning: Mosquitto should not be run as root/administrator.
1594965870: Connecting bridge main-mqtt (core-mosquitto:1883)
[23:04:30] INFO: e[32mStarting OpenZWave...e[0m
1594965870: New connection from 127.0.0.1 on port 1883.
1594965870: Socket error on client <unknown>, disconnecting.
ozw.daemon: Checking  "/data/ozw/config"  for manufacturer_specific.xml
ozw.daemon: Checking  "/data/ozw/config"  for options.xml
ozw.daemon: DBPath:  "/data/ozw/config/"
ozw.daemon: userPath:  "/data/ozw/config/"
ozw.daemon: Network Key Specified in Enviroment is Valid
ozw.daemon: Didn't Find Network Key File. Skipping
ozw.daemon: We Have what appears to be a valid Network Key - Passing to OZW
ozw.daemon: Didn't Find Auth Key File. Skipping
QT_MESSAGE_PATTERN: %{backtrace} is not supported by this Qt build
[20200716 23:04:30.343 PDT] [ozw.manager] [debug]: Database Path:  "/data/ozw/config/"  User Path "/data/ozw/config/" 
[20200716 23:04:30.344 PDT] [ozw.manager] [debug]: Models Created 
[20200716 23:04:30.346 PDT] [qt.remoteobjects] [warning]:  Tried to register QRemoteObjectRootSource twice "QTOZWLog" 
[20200716 23:04:30.346 PDT] [ozw.manager] [debug]: QTOZWManager Ready! 
[20200716 23:04:30.346 PDT] [ozw.daemon] [debug]: Ready 
[20200716 23:04:30.346 PDT] [ozw.manager] [debug]: QTOZWLog Ready! 
[20200716 23:04:30.346 PDT] [ozw.manager] [debug]: QTOZWOptions Ready! 
[20200716 23:04:30.346 PDT] [ozw.daemon] [info]: Staring  "ozwdaemon"  Version:  "0.1.0" 
[20200716 23:04:30.346 PDT] [ozw.daemon] [info]: OpenZWave Version:  "1.6.1198" 
[20200716 23:04:30.346 PDT] [ozw.daemon] [info]: QT-OpenZWave Version:  "1.2.0" 
[20200716 23:04:30.346 PDT] [ozw.daemon] [info]: QT Version:  5.12.5 
[20200716 23:04:30.346 PDT] [default] [info]: Docker MQTT_PASSWORD Secret Missing.... 
[20200716 23:04:30.347 PDT] [default] [info]: Using MQTT_PASSWORD from Enviroment 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "ping" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "open" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "close" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "refreshnodeinfo" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "requestnodestate" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "requestnodedynamic" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "requestconfigparam" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "requestallconfigparam" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "softresetcontroller" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "hardresetcontroller" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "cancelcontrollercommand" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "testnetworknode" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "testnetwork" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "healnetworknode" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "healnetwork" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "addnode" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "removenode" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "removefailednode" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "hasnodefailed" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "requestnodeneighborupdate" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "assignreturnroute" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "deleteallreturnroute" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "sendnodeinformation" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "replacefailednode" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "requestnetworkupdate" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "isnodefailed" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "checklatestconfigfilerevision" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "checklatestmfsrevision" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "downloadlatestconfigfilerevision" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "downloadlatestmfsrevision" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "setvalue" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "getpollinterval" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "setpollinterval" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "syncronisenodeneighbors" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "enablepoll" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "disablepoll" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "refreshvalue" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "addassociation" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.commands] [debug]: Registering Command  "removeassociation" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.publisher] [debug]: MQTT State Change "Connecting" 
[20200716 23:04:30.347 PDT] [ozw.mqtt.publisher] [info]: MQTT Client Connecting 
1594965870: New connection from 127.0.0.1 on port 1883.
[20200716 23:04:30.349 PDT] [ozw.library] [debug]: Always - Node: 0 OpenZwave Version 1.6-1198-g6c2ca613 Starting Up 
[20200716 23:04:30.355 PDT] [ozw.library] [info]: Info - Node: 0 Loading Localization File /data/ozw/config/Localization.xml 
[20200716 23:04:30.356 PDT] [ozw.library] [info]: Info - Node: 0 Loaded /data/ozw/config/Localization.xml With Revision 10 
[20200716 23:04:30.356 PDT] [ozw.library] [debug]: Always - Node: 0 Using Language Localization  
[20200716 23:04:30.358 PDT] [ozw.library] [info]: Info - Node: 0 Loading NotificationCCTypes File /data/ozw/config/NotificationCCTypes.xml 
[20200716 23:04:30.358 PDT] [ozw.library] [info]: Info - Node: 0 Loaded /data/ozw/config/NotificationCCTypes.xml With Revision 11 
[20200716 23:04:30.359 PDT] [ozw.library] [info]: Info - Node: 0 Loading SensorMultiLevelCCTypes File /data/ozw/config/SensorMultiLevelCCTypes.xml 
[20200716 23:04:30.359 PDT] [ozw.library] [info]: Info - Node: 0 Loaded /data/ozw/config/SensorMultiLevelCCTypes.xml With Revision 4 
[20200716 23:04:30.359 PDT] [ozw.manager] [debug]: OpenZWave Manager Instance Created 
[20200716 23:04:30.359 PDT] [ozw.manager] [debug]: OpenZWave Watcher Registered 
[20200716 23:04:30.359 PDT] [ozw.manager] [debug]: Notification Signals Setup 
[20200716 23:04:30.359 PDT] [ozw.library] [info]: Info - Node: 0 Setting Up Provided Network Key for Secure Communications 
[20200716 23:04:30.368 PDT] [ozw.library] [info]: Info - Node: 0 Manufacturer_Specific.xml file Revision is 109 
[23:04:30] INFO: e[32mSuccessfully send discovery information to Home Assistant.e[0m
[23:04:30] INFO: e[32mStarting ozw-admin...e[0m
[20200716 23:04:30.667 PDT] [ozw.library] [info]: Info - Node: 0 Product name collision: SmartCode 910 type 1 id 1 manufacturerid 90, collides with Touchpad Electronic Deadbolt, type 1 id 1 manufacturerid 90 
QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-root'
ozwadmin: Settings Path is at  "/root/.config/OpenZWave/ozw-admin.conf"
ozwadmin: Checking  "/data/ozw/config"  for manufacturer_specific.xml
ozwadmin: Checking  "/data/ozw/config"  for options.xml
ozwadmin: DBPath:  "/data/ozw/config/"
ozwadmin: userPath:  "/data/ozw/config/"
ozwadmin: Settings Path:  "/root/.config/OpenZWave/ozw-admin.conf"
ozwadmin: Using Saved Network Cache Size: 1000
QT_MESSAGE_PATTERN: %{backtrace} is not supported by this Qt build
[20200716 23:04:30.899 PDT] [ozwadmin] [info]: OZWCore Initilized QTOpenZwave(0x5593fc857680) QTOZWManager(0x5593fc858f20) 
[20200716 23:04:30.925 PDT] [ozw.library] [info]: Info - Node: 0 Queuing Lookup on mfs.db.openzwave.com for Node 0 
[20200716 23:04:30.926 PDT] [ozw.library] [info]: Info - Node: 0   Opening controller /dev/ttyUSB0 
[20200716 23:04:30.926 PDT] [ozw.library] [info]: Info - Node: 0 Trying to open serial port /dev/ttyUSB0 (attempt 1) 
[20200716 23:04:30.926 PDT] [ozw.library] [info]: Info - Node: 0 Starting DNSThread 
[20200716 23:04:30.926 PDT] [ozw.library] [info]: Info - Node: 0 Timer: thread starting 
[20200716 23:04:30.926 PDT] [ozw.library] [debug]: Detail - Node: 0 Timer: waiting with timeout -1 ms 
[20200716 23:04:30.926 PDT] [ozw.library] [info]: Info - Node: 0 mgr,     Added driver for controller /dev/ttyUSB0 
[20200716 23:04:30.926 PDT] [ozw.manager] [debug]: AddDriver Completed 
[20200716 23:04:30.926 PDT] [ozw.library] [info]: Info - Node: 0 LookupTxT Checking mfs.db.openzwave.com 
[20200716 23:04:30.927 PDT] [ozw.library] [info]: Info - Node: 0 Serial port /dev/ttyUSB0 opened (attempt 1) 
[20200716 23:04:30.927 PDT] [ozw.library] [debug]: Detail - Node: 255 Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9 
1594965870: New client connected from 127.0.0.1 as qt-openzwave-1 (p2, c1, k60, u'ozw').
[20200716 23:04:30.938 PDT] [ozw.mqtt.publisher] [debug]: MQTT State Change "Connected" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.publisher] [info]: MQTT Client Connected 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "addassociation" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/addassociation/") 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "addnode" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/addnode/") 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "assignreturnroute" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/assignreturnroute/") 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "cancelcontrollercommand" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/cancelcontrollercommand/") 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "checklatestconfigfilerevision" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/checklatestconfigfilerevision/") 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "checklatestmfsrevision" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/checklatestmfsrevision/") 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "close" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/close/") 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "deleteallreturnroute" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/deleteallreturnroute/") 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "disablepoll" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/disablepoll/") 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "downloadlatestconfigfilerevision" 
[20200716 23:04:30.938 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/downloadlatestconfigfilerevision/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "downloadlatestmfsrevision" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/downloadlatestmfsrevision/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "enablepoll" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/enablepoll/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "getpollinterval" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/getpollinterval/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "hardresetcontroller" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/hardresetcontroller/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "hasnodefailed" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/hasnodefailed/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "healnetwork" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/healnetwork/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "healnetworknode" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/healnetworknode/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "isnodefailed" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/isnodefailed/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "open" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/open/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "ping" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/ping/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "refreshnodeinfo" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/refreshnodeinfo/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "refreshvalue" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/refreshvalue/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "removeassociation" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/removeassociation/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "removefailednode" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/removefailednode/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "removenode" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/removenode/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "replacefailednode" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/replacefailednode/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestallconfigparam" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestallconfigparam/") 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestconfigparam" 
[20200716 23:04:30.939 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestconfigparam/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestnetworkupdate" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestnetworkupdate/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestnodedynamic" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestnodedynamic/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestnodeneighborupdate" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestnodeneighborupdate/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestnodestate" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestnodestate/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "sendnodeinformation" 
[20200716 23:04:30.940 PDT] [ozw.library] [info]: Info - Node: 0 Lookup for mfs.db.openzwave.com returned 109 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/sendnodeinformation/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "setpollinterval" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/setpollinterval/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "setvalue" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/setvalue/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "softresetcontroller" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/softresetcontroller/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "syncronisenodeneighbors" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/syncronisenodeneighbors/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "testnetwork" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/testnetwork/") 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "testnetworknode" 
[20200716 23:04:30.940 PDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/testnetworknode/") 
[20200716 23:04:30.955 PDT] [default] [warning]: QVncServer created on port 5900 
[20200716 23:04:30.984 PDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/status/" 
[20200716 23:04:30.984 PDT] [ozw.mqtt.publisher] [warning]: Another ozwdaemon is running for Instance  1 
[20200716 23:04:30.984 PDT] [ozw.mqtt.publisher] [warning]: If not, please clean up the MQTT Topic:  "OpenZWave/1/status/" 
[20200716 23:04:30.984 PDT] [ozw.mqtt.publisher] [warning]: "{\n    \"OpenZWave_Version\": \"1.6.1198\",\n    \"OZWDaemon_Version\": \"0.1.0\",\n    \"QTOpenZWave_Version\": \"1.2.0\",\n    \"QT_Version\": \"5.12.5\",\n    \"Status\": \"driverRemoved\",\n    \"TimeStamp\": 1594965721,\n    \"homeID\": 0\n}" 
[20200716 23:04:30.984 PDT] [ozw.library] [debug]: Detail - Node: 0 Notification: DriverRemoved 
[20200716 23:04:30.985 PDT] [ozw.notifications] [debug]: Notification pvt_driverRemoved  0 Thread:  0x7f3e855a7d48 
[20200716 23:04:30.985 PDT] [ozw.mqtt.publisher] [debug]: Publishing Event stopped 
[20200716 23:04:30.985 PDT] [ozw.mqtt.publisher] [debug]: Publishing Event driverRemoved: 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 *************************************************************************** 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 *********************  Cumulative Network Statistics  ********************* 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 *** General 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Driver run time: . .  . 0 days, 0 hours, 0 minutes 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Frames processed: . . . . . . . . . . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Total messages successfully received: . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Total Messages successfully sent: . . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 ACKs received from controller:  . . . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 *** Errors 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Unsolicited messages received while waiting for ACK:  . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Reads aborted due to timeouts:  . . . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Bad checksum errors:  . . . . . . . . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 CANs received from controller:  . . . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 NAKs received from controller:  . . . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Out of frame data flow errors:  . . . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Messages retransmitted: . . . . . . . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 Messages dropped and not delivered: . . . . . . . . . . . 0 
[20200716 23:04:30.985 PDT] [ozw.library] [debug]: Always - Node: 0 *************************************************************************** 
[20200716 23:04:30.985 PDT] [ozw.library] [warning]: Warning - Node: 0 WARNING: Tried to write driver config with no home ID set 
[20200716 23:04:30.986 PDT] [ozw.library] [info]: Info - Node: 0 Stopping DNSThread 
[23:04:31] INFO: e[32mStarting websockify...e[0m
[23:04:31] INFO: e[32mStarting ozw-admin...e[0m
QStandardPaths: XDG_RUNTIME_DIR not set, defaulting to '/tmp/runtime-root'
ozwadmin: Settings Path is at  "/root/.config/OpenZWave/ozw-admin.conf"
ozwadmin: Checking  "/data/ozw/config"  for manufacturer_specific.xml
ozwadmin: Checking  "/data/ozw/config"  for options.xml
ozwadmin: DBPath:  "/data/ozw/config/"
ozwadmin: userPath:  "/data/ozw/config/"
[20200716 23:04:31.394 PDT] [ozw.library] [debug]: Debug - Node: 0 Downloads Remaining: 0 
[20200716 23:04:31.394 PDT] [ozw.library] [info]: Info - Node: 0 ManufacturerSpecificDB Initialized 
ozwadmin: Settings Path:  "/root/.config/OpenZWave/ozw-admin.conf"
ozwadmin: Using Saved Network Cache Size: 1000
QT_MESSAGE_PATTERN: %{backtrace} is not supported by this Qt build
[20200716 23:04:31.402 PDT] [ozwadmin] [info]: OZWCore Initilized QTOpenZwave(0x55e0001f37c0) QTOZWManager(0x55e0001f4f20) 
[20200716 23:04:31.424 PDT] [default] [warning]: QVncServer created on port 5900 
WebSocket server settings:
  - Listen on :8099
  - Web server. Web root: /usr/share/novnc
  - No SSL/TLS support (no cert file)
  - proxying from :8099 to 127.0.0.1:5900
[20200716 23:04:33.396 PDT] [ozw.library] [info]: Info - Node: 0 mgr,     Driver for controller /dev/ttyUSB0 removed 
[20200716 23:04:33.396 PDT] [ozw.manager] [debug]: Driver Removed for  "/dev/ttyUSB0" 
[20200716 23:04:33.396 PDT] [ozw.manager] [debug]: OZW Serial Port Closed 
[20200716 23:04:33.396 PDT] [ozw.daemon] [info]: Shutting Down  "ozwdaemon"  Version:  "0.1.0" 
[20200716 23:04:33.396 PDT] [ozw.daemon] [info]: OpenZWave Version:  "1.6.1198" 
[20200716 23:04:33.396 PDT] [ozw.daemon] [info]: QT-OpenZWave Version:  "1.2.0" 
[20200716 23:04:33.397 PDT] [ozw.daemon] [info]: QT Version:  5.12.5 
1594965873: Client qt-openzwave-1 disconnected.
In exit
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
s6-svwait: fatal: supervisor died
1594965873: mosquitto version 1.6.8 terminating
1594965873: Saving in-memory database to /data/mosquitto.db.
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.

I also tried using MQTT explorer to clear the status, it helped insofar as I don’t have to reinstall mosquitto but it didn’t work to get openzwave working again without reinstalling that so I’m with you on that one. My status would say “driverRemoved” instead of driverReady though too

I think I just fixed the hass openzwave addon for at least my case.
There are two MQTT servers that you need to fix. The first is your MQTT server which is my mosquitto addon. I use MQTT explorer and delete the entire openzwave\1 topic.
The second MQTT server is the internal one in the openzwave container. Since I’m using ubuntu supervised, my open zwave container is located in /usr/share/hassio/addons/data/core_zwave
I just delete the mosquitto.db file using

sudo rm /usr/share/hassio/addons/data/core_zwave/mosquitto.db

Restart the addon and voila it’s working again! There is a bug where it’s not cleaning up the internal mqtt server when the container is shut down.

There’s an issue already filed, you might want to comment on the problem and your workaround there.

1 Like

Thanks, I will post in that issue!

I have just 1 week ago migrated from Domoticz to hass.io and I’m super happy, there are differences but the setup was super easy (running on VMware ESX) and it has been fairly easy to adjust.

I do have the problem that my Z-Wave (AEOTEC Z-Stick gen 5) is not working after reboot. So day 2 of me using HA I created a new virtual machine with the provided vmdk-file and everything was working great, I didn’t dare to reboot but just restarting HA-service works fine.

Yesterday I was fiddling around and I remember that there was an upgrade to downgrade some modules? I thought it might have been related to the issue with ZWave so I rebooted just to test. Unfortunately the problem occurred again… I get some different error messages but they are basically the same as already mentioned.

I tried the suggestion to remove the mosquitto.db (mine was at /mnt/data/supervisor/addons/data/core_zwave/mosquitto.db as mentioned by kmpm in the bug report comments).

at the same time, I also removed all the messages with MQTT Explorer.

after that I rebooted the system completely and then I got the familiar error messages in the logs:

[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.968 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [debug]: Debug - Node: 0 Value Deleted 
[20200719 13:58:24.969 CEST] [ozw.library] [info]: Info - Node: 0 mgr,     Driver for controller /dev/serial/by-id/usb-0658_0200-if00 removed 
[20200719 13:58:24.969 CEST] [ozw.manager] [debug]: Driver Removed for  "/dev/serial/by-id/usb-0658_0200-if00" 
[20200719 13:58:24.969 CEST] [ozw.manager] [debug]: OZW Serial Port Closed 
[20200719 13:58:24.969 CEST] [ozw.daemon] [info]: Shutting Down  "ozwdaemon"  Version:  "0.1.0" 
[20200719 13:58:24.969 CEST] [ozw.daemon] [info]: OpenZWave Version:  "1.6.1198" 
[20200719 13:58:24.969 CEST] [ozw.daemon] [info]: QT-OpenZWave Version:  "1.2.0" 
[20200719 13:58:24.969 CEST] [ozw.daemon] [info]: QT Version:  5.12.5 
[20200719 13:58:24.980 CEST] [ozw.manager] [info]: New Client WebSocket Connection QHostAddress("::ffff:127.0.0.1") 
[20200719 13:58:24.980 CEST] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_NoOperation Thread:  0x7f18ca6cdd48 
[20200719 13:58:24.980 CEST] [ozw.notifications] [debug]: Notification pvt_nodeProtocolInfo  21 Thread:  0x7f18ca6cdd48 
[20200719 13:58:24.980 CEST] [ozw.library] [critical]: Error - Node: 0 mgr,     Manager::GetDriver failed - Home ID 0x00000000 is unknown 
[20200719 13:58:24.980 CEST] [ozw.library] [warning]: Warning - Node: 0 Exception: Manager.cpp:410 - 100 - Invalid HomeId passed to GetDriver 
[20200719 13:58:24.981 CEST] [ozw.notifications] [warning]: OZW Exception:  Invalid HomeId passed to GetDriver  at  /usr/src/ozw/cpp/src/Manager.cpp : 410 
[20200719 13:58:24.983 CEST] [ozw.manager] [warning]: WebSocket Client Error  QAbstractSocket::RemoteHostClosedError 
[20200719 13:58:24.983 CEST] [ozwadmin] [debug]: Remote Connection Status:  QTOZWManager::ConnectionErrorState QAbstractSocket::RemoteHostClosedError 
In exit
Terminating child 497
1595159905: mosquitto version 1.6.8 terminating
1595159905: Saving in-memory database to /data/mosquitto.db.
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
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/websockifyserver.py", line 691, in top_new_client
    client = self.do_handshake(startsock, address)
  File "/usr/lib/python3.8/site-packages/websockify/websockifyserver.py", line 619, in do_handshake
    self.RequestHandlerClass(retsock, address, self)
  File "/usr/lib/python3.8/site-packages/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/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/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/websocketserver.py", line 60, in _websocket_do_GET
    self.handle_upgrade()
  File "/usr/lib/python3.8/site-packages/websockify/websockifyserver.py", line 221, in handle_upgrade
    WebSocketRequestHandlerMixIn.handle_upgrade(self)
  File "/usr/lib/python3.8/site-packages/websockify/websocketserver.py", line 87, in handle_upgrade
    self.handle_websocket()
  File "/usr/lib/python3.8/site-packages/websockify/websockifyserver.py", line 259, in handle_websocket
    self.new_websocket_client()
  File "/usr/lib/python3.8/site-packages/websockify/websocketproxy.py", line 134, in new_websocket_client
    self.do_proxy(tsock)
  File "/usr/lib/python3.8/site-packages/websockify/websocketproxy.py", line 209, in do_proxy
    ins, outs, excepts = select.select(rlist, wlist, [], 1)
  File "/usr/lib/python3.8/site-packages/websockify/websockifyserver.py", line 683, in do_SIGTERM
    self.terminate()
  File "/usr/lib/python3.8/site-packages/websockify/websockifyserver.py", line 656, in terminate
    raise self.Terminate()
websockify.websockifyserver.WebSockifyServer.Terminate
s6-svwait: fatal: supervisor died
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.

so, unfortunately, it didn’t work. I have also tried the same procedure and just start the openszwave service.

any comments or suggestions are more than welcome! I really do like HA, as long as it works! :smiley: