OpenZWave not working after update

Seems to be working fine now.

I had the same problem and a restart of MQTT broker did the trick.

I’m having the same issue however even after clearing the open zwave status topic with MQTT explorer the open zwave addon will fail to start erroring out on the same error. Hoping for some guidance from one of our wonderful developers or users.

Oddly this only happens if I use the official addon. Running the OZW daemon on another host works great.

If not, please clean up the MQTT Topic: "OpenZWave/1/status/"

Here is the addon log.

[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/hasnodefailed/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "healnetwork" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/healnetwork/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "healnetworknode" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/healnetworknode/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "isnodefailed" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/isnodefailed/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "open" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/open/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "ping" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/ping/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "refreshnodeinfo" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/refreshnodeinfo/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "refreshvalue" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/refreshvalue/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "removeassociation" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/removeassociation/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "removefailednode" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/removefailednode/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "removenode" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/removenode/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "replacefailednode" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/replacefailednode/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestallconfigparam" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestallconfigparam/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestconfigparam" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestconfigparam/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestnetworkupdate" 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestnetworkupdate/") 
[20200710 12:00:11.078 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestnodedynamic" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestnodedynamic/") 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestnodeneighborupdate" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestnodeneighborupdate/") 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "requestnodestate" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/requestnodestate/") 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "sendnodeinformation" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/sendnodeinformation/") 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "setpollinterval" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/setpollinterval/") 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "setvalue" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/setvalue/") 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "softresetcontroller" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/softresetcontroller/") 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "syncronisenodeneighbors" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/syncronisenodeneighbors/") 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "testnetwork" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/testnetwork/") 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Creating Subscription for  "testnetworknode" 
[20200710 12:00:11.079 CDT] [ozw.mqtt.commands] [debug]: Subscription Setup for  QMqttTopicFilter("OpenZWave/1/command/testnetworknode/") 
[20200710 12:00:11.121 CDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/status/" 
[20200710 12:00:11.122 CDT] [ozw.mqtt.publisher] [warning]: Another ozwdaemon is running for Instance  1 
[20200710 12:00:11.122 CDT] [ozw.mqtt.publisher] [warning]: If not, please clean up the MQTT Topic:  "OpenZWave/1/status/" 
[20200710 12:00:11.122 CDT] [ozw.mqtt.publisher] [warning]: "{\n    \"OpenZWave_Version\": \"1.6.1201\",\n    \"OZWDaemon_Version\": \"0.1.0\",\n    \"QTOpenZWave_Version\": \"1.2.0\",\n    \"QT_Version\": \"5.14.2\",\n    \"Status\": \"driverRemoved\",\n    \"TimeStamp\": 1594400224,\n    \"homeID\": 0\n}" 
[20200710 12:00:11.123 CDT] [ozw.library] [debug]: Detail - Node: 0 Notification: DriverRemoved 
[20200710 12:00:11.123 CDT] [ozw.notifications] [debug]: Notification pvt_driverRemoved  0 Thread:  0x7fe590c93d48 
[20200710 12:00:11.123 CDT] [ozw.mqtt.publisher] [debug]: Publishing Event stopped 
[20200710 12:00:11.123 CDT] [ozw.mqtt.publisher] [debug]: Publishing Event driverRemoved: 0 
[20200710 12:00:11.124 CDT] [ozw.library] [debug]: Always - Node: 0 *************************************************************************** 
[20200710 12:00:11.124 CDT] [ozw.library] [debug]: Always - Node: 0 *********************  Cumulative Network Statistics  ********************* 
[20200710 12:00:11.125 CDT] [ozw.library] [debug]: Always - Node: 0 *** General 
[20200710 12:00:11.125 CDT] [ozw.library] [debug]: Always - Node: 0 Driver run time: . .  . 0 days, 0 hours, 0 minutes 
[20200710 12:00:11.125 CDT] [ozw.library] [debug]: Always - Node: 0 Frames processed: . . . . . . . . . . . . . . . . . . . . 0 
[20200710 12:00:11.125 CDT] [ozw.library] [debug]: Always - Node: 0 Total messages successfully received: . . . . . . . . . . 0 
[20200710 12:00:11.126 CDT] [ozw.library] [debug]: Always - Node: 0 Total Messages successfully sent: . . . . . . . . . . . . 0 
[20200710 12:00:11.126 CDT] [ozw.library] [debug]: Always - Node: 0 ACKs received from controller:  . . . . . . . . . . . . . 0 
[20200710 12:00:11.126 CDT] [ozw.library] [debug]: Always - Node: 0 *** Errors 
[20200710 12:00:11.126 CDT] [ozw.library] [debug]: Always - Node: 0 Unsolicited messages received while waiting for ACK:  . . 0 
[20200710 12:00:11.126 CDT] [ozw.library] [debug]: Always - Node: 0 Reads aborted due to timeouts:  . . . . . . . . . . . . . 0 
[20200710 12:00:11.126 CDT] [ozw.library] [debug]: Always - Node: 0 Bad checksum errors:  . . . . . . . . . . . . . . . . . . 0 
[20200710 12:00:11.126 CDT] [ozw.library] [debug]: Always - Node: 0 CANs received from controller:  . . . . . . . . . . . . . 0 
[20200710 12:00:11.127 CDT] [ozw.library] [debug]: Always - Node: 0 NAKs received from controller:  . . . . . . . . . . . . . 0 
[20200710 12:00:11.127 CDT] [ozw.library] [debug]: Always - Node: 0 Out of frame data flow errors:  . . . . . . . . . . . . . 0 
[20200710 12:00:11.127 CDT] [ozw.library] [debug]: Always - Node: 0 Messages retransmitted: . . . . . . . . . . . . . . . . . 0 
[20200710 12:00:11.127 CDT] [ozw.library] [debug]: Always - Node: 0 Messages dropped and not delivered: . . . . . . . . . . . 0 
[20200710 12:00:11.127 CDT] [ozw.library] [debug]: Always - Node: 0 *************************************************************************** 
[20200710 12:00:11.127 CDT] [ozw.library] [warning]: Warning - Node: 0 WARNING: Tried to write driver config with no home ID set 
[20200710 12:00:11.127 CDT] [ozw.library] [info]: Info - Node: 0 Stopping DNSThread 
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
[20200710 12:00:11.746 CDT] [ozw.library] [debug]: Debug - Node: 0 Downloads Remaining: 0 
[20200710 12:00:11.746 CDT] [ozw.library] [info]: Info - Node: 0 ManufacturerSpecificDB Initialized 
[20200710 12:00:12.015 CDT] [ozw.library] [info]: Info - Node: 0 mgr,     Driver for controller /dev/serial/by-id/usb-0658_0200-if00 removed 
[20200710 12:00:12.016 CDT] [ozw.manager] [debug]: Driver Removed for  "/dev/serial/by-id/usb-0658_0200-if00" 
[20200710 12:00:12.016 CDT] [ozw.manager] [debug]: OZW Serial Port Closed 
[20200710 12:00:12.016 CDT] [ozw.daemon] [info]: Shutting Down  "ozwdaemon"  Version:  "0.1.0" 
[20200710 12:00:12.016 CDT] [ozw.daemon] [info]: OpenZWave Version:  "1.6.1201" 
[20200710 12:00:12.016 CDT] [ozw.daemon] [info]: QT-OpenZWave Version:  "1.2.0" 
[20200710 12:00:12.017 CDT] [ozw.daemon] [info]: QT Version:  5.14.2 
1594400412: Client qt-openzwave-1 disconnected.
In exit
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
1594400412: mosquitto version 1.6.9 terminating
1594400412: 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.

Mine is doing the same. Seems like something wasn’t cleaned up when upgrading.

[20200710 15:41:42.629 MDT] [ozw.mqtt.publisher] [warning]: Another ozwdaemon is running for Instance  1 
[20200710 15:41:42.630 MDT] [ozw.mqtt.publisher] [warning]: If not, please clean up the MQTT Topic:  "OpenZWave/1/status/" 
[20200710 15:41:42.630 MDT] [ozw.mqtt.publisher] [warning]: "{\n    \"OpenZWave_Version\": \"1.6.1201\",\n    \"OZWDaemon_Version\": \"0.1.0\",\n    \"QTOpenZWave_Version\": \"1.2.0\",\n    \"QT_Version\": \"5.14.2\",\n    \"Status\": \"driverRemoved\",\n    \"TimeStamp\": 1594417032,\n    \"homeID\": 0\n}"

I don’t have an another instance running unless supervisor started two of them somehow. I have went ahead and reloaded the entire Pi so it’ should be “fresh”

I just found this thread after opening my own and I think now it may be related to what I was seeing, so will close that as this may make more sense as I cannot get the OpenZwave instance to connect to the MQTT server and not sure why. I’m migrating from Homeseer to HA, and working the last piece to move over my Zwave network. I’ve been following the docs, but have to be missing something as no devices/entities are showing up on the HA side.

I am using Supervisor, so loaded up OpenZWave 0.5.0, set the device and network key. If I connect via VNC, I connect to the local instance on 1983, and I see my Zooz usb stick and all the nodes and traffic in it’s log. The logs from OpenZWave seem to show the same. It also auto-detected the OpenZwave Beta integration and I installed it.

However I noticed if I connect to my mqtt server with MQTT Explorer, while I see all my mqtt traffic (including from home assistant), I don’t see anything from OpenZWave for topics. I am using the MQTT integration in HA (installed thru the UI), and other mqtt traffic is working fine. Am I missing a configuration step for it to find the correct broker to use?

OpenZwave Configuation:

device: /dev/serial/by-id/usb-0658_0200-if00
network_key: '0x00... REMOVED'
instance: 1

Logs:

[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/94/value/718766097/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/94/value/281475695476758/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/94/value/281475695476758/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/94/value/562950672187414/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/94/value/562950672187414/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/114/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/114/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/114/value/719093779/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/114/value/719093779/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/114/value/281475695804435/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/114/value/281475695804435/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/114/value/562950672515091/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/114/value/562950672515091/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/114/value/1125900625936407/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/114/value/1125900625936407/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/719110164/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/719110164/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/281475695820817/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/281475695820817/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/562950672531480/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/562950672531480/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/844425649242129/" 
[20200710 13:38:22.089 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/844425649242129/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/1125900625952788/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/1125900625952788/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/1407375602663446/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/1407375602663446/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/1688850579374104/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/1688850579374104/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/1970325556084760/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/1970325556084760/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/2251800532795412/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/2251800532795412/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/2533275509506070/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/115/value/2533275509506070/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/134/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/134/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/134/value/719421463/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/134/value/719421463/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/134/value/281475696132119/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/134/value/281475696132119/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/instance/1/commandclass/134/value/562950672842775/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/instance/1/commandclass/134/value/562950672842775/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/association/1/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/association/1/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Topics:  "OpenZWave/1/node/42/association/2/" 
[20200710 13:38:22.090 EDT] [ozw.mqtt.publisher] [debug]: Removing Stale Topic/Msg:  "OpenZWave/1/node/42/association/2/" 
[13:38:22] INFO: Starting websockify...
[13:38:22] 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
[20200710 13:38:22.259 EDT] [ozwadmin] [info]: OZWCore Initilized QTOpenZwave(0x7fae59a90b00) QTOZWManager(0x55abcab47380) 
[20200710 13:38:22.276 EDT] [default] [warning]: QVncServer created on port 5900 
[20200710 13:38:22.532 EDT] [ozw.library] [debug]: Debug - Node: 0 Downloads Remaining: 2 
[20200710 13:38:22.532 EDT] [ozw.library] [warning]: Warning - Node: 0 Download of http://download.db.openzwave.com/images/honeywell/39348-ZW4005.jpg Failed (Node: 0) 
[20200710 13:38:22.532 EDT] [ozw.library] [debug]: Debug - Node: 0 Downloads Remaining: 1 
[20200710 13:38:22.532 EDT] [ozw.library] [debug]: Detail - Node: 0 Notification: A Config File Failed to download 
[20200710 13:38:22.532 EDT] [ozw.library] [warning]: Warning - Node: 0 Download of http://download.db.openzwave.com/images/honeywell/39449-ZW4016.png Failed (Node: 0) 
[20200710 13:38:22.532 EDT] [ozw.notifications] [debug]: Notification pvt_ozwUserAlert NotificationTypes::Notification_User_ConfigFileDownloadFailed Thread:  0x7f2ca8ae9d48 
[20200710 13:38:22.532 EDT] [ozw.library] [debug]: Debug - Node: 0 Downloads Remaining: 0 
[20200710 13:38:22.532 EDT] [ozw.library] [info]: Info - Node: 0 ManufacturerSpecificDB Initialized 
[20200710 13:38:22.532 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event ozwUserAlert 
[20200710 13:38:22.532 EDT] [ozw.library] [debug]: Detail - Node: 0 Notification: A Config File Failed to download 
[20200710 13:38:22.532 EDT] [ozw.library] [debug]: Detail - Node: 0 Notification: ManufacturerSpecificDB Ready 
[20200710 13:38:22.532 EDT] [ozw.notifications] [debug]: Notification pvt_ozwUserAlert NotificationTypes::Notification_User_ConfigFileDownloadFailed Thread:  0x7f2ca8ae9d48 
[20200710 13:38:22.532 EDT] [ozw.library] [info]: Info - Node: 255 Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9 
[20200710 13:38:22.532 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event ozwUserAlert 
[20200710 13:38:22.532 EDT] [ozw.library] [info]: Info - Node: 255 Encrypted Flag is 0 
[20200710 13:38:22.532 EDT] [ozw.notifications] [debug]: Notification pvt_manufacturerSpecificDBReady Thread:  0x7f2ca8ae9d48 
[20200710 13:38:22.533 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event manufacturerSpecificDBReady 
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
[20200710 13:38:23.533 EDT] [ozw.library] [critical]: Error - Node: 255 ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9" 
[20200710 13:38:23.533 EDT] [ozw.library] [debug]: Detail - Node: 255 Removing current message 
[20200710 13:38:23.533 EDT] [ozw.library] [debug]: Detail - Node: 255 Notification: Notification - TimeOut 
[20200710 13:38:23.533 EDT] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_MsgTimeout Thread:  0x7f2ca8ae9d48 
[20200710 13:38:23.533 EDT] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification 
[20200710 13:38:33.062 EDT] [ozw.library] [info]: Info - Node: 0 HttpThread Exiting. No Transfers in timeout period 
[20200710 13:38:33.073 EDT] [ozw.library] [info]: Info - Node: 0 HttpThread Exiting. No Transfers in timeout period 
[20200710 13:39:22.496 EDT] [ozw.mqtt.publisher] [debug]: "Fri Jul 10 13:39:22 2020 PingResponse\n" 

So I just ran into this issue today. I got it to work by uninstalling Open Z Wave and MQTT then doing a reboot. After installing MQTT and going through the reconfigure it seems to be working fine. I am using a Z-Stick so most of my devices are stored there so it is now just a matter of time before it all reports in.

2 Likes

Update: I just noticed that my smart switch was not responding. I did another reboot and now I am back at square 1.

1 Like

I “upgraded” (which seems like a rollback of the components to older version) to 0.5.1 and I have the same problem.

@nemith when you say the same problem is it what I am seeing where it won’t connect to the MQTT broker or is it a different one? It’s my first time using this integration, and never gotten it to work. Thanks!

No yours seems different. The issue I am seeing is the same as kenbshinn, warllo54 and what freshcoast explained.

1 Like

@nemith Thanks!

Did you already try what was suggested, to manually cleanup the MQTT topics?

Can’t tell what went wrong in the first place since these aren’t the complete logs. From what is shown, that last logs show there might have been problems talking to the USB stick.

[20200710 13:38:23.533 EDT] [ozw.library] [critical]: Error - Node: 255 ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9"

If you go and look att the mqtt log there is this rows every time you try to start the addon.

1594669113: New connection from 172.30.33.4 on port 1883.
1594669113: New bridge connected from 172.30.33.4 as core-zwave.main-mqtt (p2, c0, k60, u’addons’).
1594669121: Socket error on client core-zwave.main-mqtt, disconnecting.

1 Like

@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!