The Future of Z-Wave in HA - QT-OpenZWave

freshcoast · July 15, 2020, 4:49pm

Are you using the addon or standalone docker?

sender · July 15, 2020, 6:50pm

Since “upgrade - but - downgrade” I have a very instable zwave setup… all seems normal but nothing responses… this is the latest snippet of the log:

[20200715 20:45:27.485 CEST] [ozw.library] [debug]: Detail - Node: 11 Queuing (Send) MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Set (Node=11): 0x01, 0x0e, 0x00, 0x13, 0x0b, 0x07, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x01, 0xff, 0x25, 0x9e, 0xe0 
[20200715 20:45:27.485 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:27.485 CEST] [ozw.library] [debug]: Detail - Node: 11 Queuing (Send) MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Get (Node=11): 0x01, 0x0d, 0x00, 0x13, 0x0b, 0x06, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x02, 0x25, 0x9f, 0x1f 
[20200715 20:45:27.485 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:27.486 CEST] [ozw.library] [info]: Info - Node: 11 Sending (Send) message (Callback ID=0x9e, Expected Reply=0x13) - MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Set (Node=11): 0x01, 0x0e, 0x00, 0x13, 0x0b, 0x07, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x01, 0xff, 0x25, 0x9e, 0xe0 
[20200715 20:45:27.486 CEST] [ozw.mqtt.commands] [info]: Processed Message for  "setvalue" :  "{\"ValueIDKey\": 189349936, \"Value\": true}" 
[20200715 20:45:27.486 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:27.486 CEST] [ozw.library] [info]: Info - Node: 11 Encrypted Flag is 0 
[20200715 20:45:27.486 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:28.487 CEST] [ozw.library] [critical]: Error - Node: 11 ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Set (Node=11): 0x01, 0x0e, 0x00, 0x13, 0x0b, 0x07, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x01, 0xff, 0x25, 0x9e, 0xe0" 
[20200715 20:45:28.487 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:28.488 CEST] [ozw.library] [debug]: Detail - Node: 11 Removing current message 
[20200715 20:45:28.488 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:28.489 CEST] [ozw.library] [debug]: Detail - Node: 11 Notification: Notification - TimeOut 
[20200715 20:45:28.489 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:28.489 CEST] [ozw.library] [info]: Info - Node: 11 Sending (Send) message (Callback ID=0x9f, Expected Reply=0x04) - MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Get (Node=11): 0x01, 0x0d, 0x00, 0x13, 0x0b, 0x06, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x02, 0x25, 0x9f, 0x1f 
[20200715 20:45:28.489 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:28.489 CEST] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_MsgTimeout Thread:  0x7f02056c2d48 
[20200715 20:45:28.489 CEST] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification 
[20200715 20:45:28.490 CEST] [ozw.library] [info]: Info - Node: 11 Encrypted Flag is 0 
[20200715 20:45:28.490 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:28.492 CEST] [ozwadmin] [debug]: NotificationTypes::Notification_Code_MsgTimeout 
[20200715 20:45:29.490 CEST] [ozw.library] [critical]: Error - Node: 11 ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "MultiChannel Encapsulated (instance=3): SwitchBinaryCmd_Get (Node=11): 0x01, 0x0d, 0x00, 0x13, 0x0b, 0x06, 0x60, 0x0d, 0x01, 0x02, 0x25, 0x02, 0x25, 0x9f, 0x1f" 
[20200715 20:45:29.491 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:29.492 CEST] [ozw.library] [debug]: Detail - Node: 11 Removing current message 
[20200715 20:45:29.492 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:29.493 CEST] [ozw.library] [debug]: Detail - Node: 11 Notification: Notification - TimeOut 
[20200715 20:45:29.493 CEST] [ozw.logging] [debug]: popping Log Mesages 
[20200715 20:45:29.493 CEST] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_MsgTimeout Thread:  0x7f02056c2d48 
[20200715 20:45:29.493 CEST] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification 
[20200715 20:45:29.495 CEST] [ozwadmin] [debug]: NotificationTypes::Notification_Code_MsgTimeout 
[20200715 20:45:30.995 CEST] [ozw.mqtt.commands] [debug]: Got  "OpenZWave/1/command/setvalue/"  Message:  "{\"ValueIDKey\": 189349936, \"Value\": true}" 
[20200715 20:45:30.995 CEST] [ozw.mqtt.commands.setValue] [info]: Setting  189349936  to Value  QVariant(bool, true) 
[20200715 20:45:30.995 CEST] [ozw.mqtt.commands] [info]: Processed Message for  "setvalue" :  "{\"ValueIDKey\": 189349936, \"Value\": true}" 
[20200715 20:45:42.307 CEST] [default] [debug]: QVariant(int, 1) 
[20200715 20:45:42.308 CEST] [default] [debug]: QVariant(int, 2) 
[20200715 20:45:42.308 CEST] [default] [debug]: QVariant(int, 3) 
[20200715 20:45:42.308 CEST] [default] [debug]: QVariant(int, 4) 
[20200715 20:45:42.308 CEST] [default] [debug]: QVariant(int, 5) 
[20200715 20:45:42.308 CEST] [default] [debug]: QVariant(int, 7) 
[20200715 20:45:42.308 CEST] [default] [debug]: QVariant(int, 8) 
[20200715 20:45:42.308 CEST] [default] [debug]: QVariant(int, 9) 
[20200715 20:45:42.308 CEST] [default] [debug]: QVariant(int, 10) 
[20200715 20:45:42.309 CEST] [default] [debug]: QVariant(int, 11) 
[20200715 20:45:42.309 CEST] [default] [debug]: QVariant(int, 12) 
[20200715 20:45:42.309 CEST] [default] [debug]: QVariant(int, 13) 
[20200715 20:45:42.309 CEST] [default] [debug]: QVariant(int, 14) 
[20200715 20:45:42.309 CEST] [default] [debug]: QVariant(int, 15) 
[20200715 20:45:42.309 CEST] [default] [debug]: QVariant(int, 16) 
1594838752: Saving in-memory database to /data/mosquitto.db.
[20200715 20:46:11.307 CEST] [ozw.mqtt.publisher] [debug]: "Wed Jul 15 20:46:11 2020 PingResponse\n" 
[20200715 20:46:12.307 CEST] [default] [debug]: QVariant(int, 1) 
[20200715 20:46:12.308 CEST] [default] [debug]: QVariant(int, 2) 
[20200715 20:46:12.308 CEST] [default] [debug]: QVariant(int, 3) 
[20200715 20:46:12.308 CEST] [default] [debug]: QVariant(int, 4) 
[20200715 20:46:12.309 CEST] [default] [debug]: QVariant(int, 5) 
[20200715 20:46:12.309 CEST] [default] [debug]: QVariant(int, 7) 
[20200715 20:46:12.309 CEST] [default] [debug]: QVariant(int, 8) 
[20200715 20:46:12.309 CEST] [default] [debug]: QVariant(int, 9) 
[20200715 20:46:12.309 CEST] [default] [debug]: QVariant(int, 10) 
[20200715 20:46:12.309 CEST] [default] [debug]: QVariant(int, 11) 
[20200715 20:46:12.310 CEST] [default] [debug]: QVariant(int, 12) 
[20200715 20:46:12.310 CEST] [default] [debug]: QVariant(int, 13) 
[20200715 20:46:12.310 CEST] [default] [debug]: QVariant(int, 14) 
[20200715 20:46:12.310 CEST] [default] [debug]: QVariant(int, 15) 
[20200715 20:46:12.310 CEST] [default] [debug]: QVariant(int, 16) 
[20200715 20:46:42.307 CEST] [default] [debug]: QVariant(int, 1) 
[20200715 20:46:42.307 CEST] [default] [debug]: QVariant(int, 2) 
[20200715 20:46:42.307 CEST] [default] [debug]: QVariant(int, 3) 
[20200715 20:46:42.307 CEST] [default] [debug]: QVariant(int, 4) 
[20200715 20:46:42.307 CEST] [default] [debug]: QVariant(int, 5) 
[20200715 20:46:42.308 CEST] [default] [debug]: QVariant(int, 7) 
[20200715 20:46:42.308 CEST] [default] [debug]: QVariant(int, 8) 
[20200715 20:46:42.308 CEST] [default] [debug]: QVariant(int, 9) 
[20200715 20:46:42.308 CEST] [default] [debug]: QVariant(int, 10) 
[20200715 20:46:42.308 CEST] [default] [debug]: QVariant(int, 11) 
[20200715 20:46:42.308 CEST] [default] [debug]: QVariant(int, 12) 
[20200715 20:46:42.308 CEST] [default] [debug]: QVariant(int, 13) 
[20200715 20:46:42.308 CEST] [default] [debug]: QVariant(int, 14) 
[20200715 20:46:42.309 CEST] [default] [debug]: QVariant(int, 15) 
[20200715 20:46:42.309 CEST] [default] [debug]: QVariant(int, 16) 
[20200715 20:46:43.398 CEST] [ozw.mqtt.commands] [debug]: Got  "OpenZWave/1/command/setvalue/"  Message:  "{\"ValueIDKey\": 189349936, \"Value\": true}" 
[20200715 20:46:43.399 CEST] [ozw.mqtt.commands.setValue] [info]: Setting  189349936  to Value  QVariant(bool, true) 
[20200715 20:46:43.399 CEST] [ozw.mqtt.commands] [info]: Processed Message for  "setvalue" :  "{\"ValueIDKey\": 189349936, \"Value\": true}" 
[20200715 20:47:11.307 CEST] [ozw.mqtt.publisher] [debug]: "Wed Jul 15 20:47:11 2020 PingResponse\n" 
[20200715 20:47:12.307 CEST] [default] [debug]: QVariant(int, 1) 
[20200715 20:47:12.307 CEST] [default] [debug]: QVariant(int, 2) 
[20200715 20:47:12.307 CEST] [default] [debug]: QVariant(int, 3) 
[20200715 20:47:12.307 CEST] [default] [debug]: QVariant(int, 4) 
[20200715 20:47:12.307 CEST] [default] [debug]: QVariant(int, 5) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 7) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 8) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 9) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 10) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 11) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 12) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 13) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 14) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 15) 
[20200715 20:47:12.308 CEST] [default] [debug]: QVariant(int, 16)

I do not know where to start troubleshooting expect restart all and then it will probably start working… any help appreciated!

OpenZWave

Current version: 0.5.1

mew1033 · July 15, 2020, 10:51pm

Standalone docker. Here’s the relevant bits of my docker-compse file:

version: '3.7'

services:
  homeassistant:
    # image: homeassistant/home-assistant:dev
    build:
      context: ./
      dockerfile: Dockerfile-patch-home-assistant-climate
      network: none
    container_name: home-assistant
# Can't auto update while I'm using a custom container
#    labels:
#      com.centurylinklabs.watchtower.enable: true
    volumes:
      - './home-assistant/config/:/config'
    environment:
      TZ: America/Denver
      VIRTUAL_HOST: my-vhost
      VIRTUAL_PORT: "8123"
      LETSENCRYPT_HOST: my-vhost
    devices:
      - /dev/ttyUSB0:/dev/ttyUSB0
      - /dev/ttyUSB1:/dev/ttyUSB1
    networks:
      proxy-net:
      default:
    expose:
      - "8123"

  mqtt:
    container_name: mosquitto
    image: eclipse-mosquitto
    labels:
      com.centurylinklabs.watchtower.enable: true
    ports:
      - "1883:1883"
    expose:
      - "1883"
      - "9001"
    volumes:
      - "./mosquitto/:/mosquitto/config:ro"
      - "mosquitto-logs:/mosquitto/log"
      - "mosquitto-data:/mosquitto/data"
    networks:
      default:

  qt-openzwave:
    image: openzwave/ozwdaemon:allinone-latest
    container_name: "qt-openzwave"
    labels:
      com.centurylinklabs.watchtower.enable: true
    devices:
      - "/dev/ttyUSB0:/dev/ttyUSB0"
    volumes:
      - ./qt-openzwave/config:/opt/ozw/config
    environment:
      USB_PATH: "/dev/ttyUSB0"
      MQTT_SERVER: mqtt
      MQTT_USERNAME: qt-openzwave-user
    secrets:
      - OZW_Network_Key
      - source: QT_OpenZeave_MQTT_Password
        target: MQTT_PASSWORD
    networks:
      default:
    ports:
      - "7800:7800"
      - "1983:1983"

I just realized that I’m passing my zwave stick into both home assistant AND qt-openzwave, but I don’t think that’s part of the problem.

freshcoast · July 16, 2020, 12:05am

Switch to image allinone-build-150 for now.

ha_steve · July 16, 2020, 1:34am

I just bought one of these and will be setting it up this weekend. I will also have a DHT22.

For what it’s worth, I have a home energy meter that I had set to report power usage every 15s running under 1.4 for years, and ever since switching to the beta. Same as you, when I start the daemon, it will report for a few times and then stop. The thing is like 7 years old though, so could be a coincidence…

Veuchez · July 16, 2020, 5:05am

I have two Smart Meter Qubino energy meters and they work great on both 1.4 and ozw beta.
Smart Implant worked under 1.4 but under ozw beta no …
it only reports the first measurement, not some measurement …
Let me know when it arrives if it works please!

mew1033 · July 17, 2020, 7:39pm

Yup, that fixed it. Thanks!
Do you know what the bug is or have any reference to it? I’d love to read up on and follow it.
Thanks again.

ha_steve · July 18, 2020, 6:55pm

@Veuchez I have the smart implant going and hooked up to my DHT22. However, I cannot get a reading from the DHT22. Can you tell me how you go that to happen and what instance it shows under?

FWIW, the internal temperature sensor is working and periodic reports work as expected. I am just unable see anything related to DHT22 (or the fact one is even connected)

Veuchez · July 18, 2020, 8:23pm

Really? periodic reports do not work for me, neither from the internal sensor nor from the external sensor.
How did you configure it to receive reports?
The Smart Implant must be added to OZW only after connecting the DHT22 sensor.
If you connect the DHT22 sensor when the Smart Implant is already associated with OZW then you don’t see it.
Try to remove the node and add it again.
Maybe even reset the Smart Implant.

ha_steve · July 18, 2020, 8:41pm

I used ozwadmin, on the config tab, I changed the value of:

to be 60 (which is the smallest value possible. 0=disabled). It reports every 60s from then on.

Crap, that sucks about the DHT22. I’ll have to repeat a bunch of setup

Veuchez · July 18, 2020, 8:44pm

Same configuration but I don’t receive reports …

Veuchez · July 18, 2020, 8:52pm

What version of the firmware do you have? 5.1?
How did you add the node? safe or unsafe?

ha_steve · July 19, 2020, 7:57pm

Not sure what the firmware version is, but this is what I see in ozwadmin:

Mine is “unsafe”

I also excluded and re-included so I could get the DHT22 working. I confirmed I get periodic reports with the external sensor as well (set to 60s, I get temp/humidity every 60s)

Veuchez · July 19, 2020, 8:21pm

Problem solved.
By including the Smart Implant by pressing the button on the Aeotec stick instead of from OZW, the node does not associate correctly and the values do not update.
If I include it directly from OZW everything works!

ha_steve · July 19, 2020, 11:40pm

Oh yeah… that’s a known issue. Don’t use the button on the Aeotec stick to include things…

Veuchez · July 20, 2020, 5:36am

I did not know!
Luckily it occurred to me to try this!

Veuchez · July 21, 2020, 4:25pm

How are the reports from the DHT22 sensor going?
I went back to the starting point.
After a while the reports from the external sensor stop, while those from the internal sensor continue to receive them.
If I disconnect and reconnect the power supply, everything works again.
It makes me the same problem even if I connect it on the Fibaro control panel.
I have already changed 2 Smart Implants and 3 DHT22.
Here are the graphs.
Some idea?

Internal Sensor - Temperature
INTERNAL TEMPERATURE

External Sensor - Temperature
EXTERNAL TEMPERATURE

External Sensor - Humidity
UMIDITA'

Config
CONFIG

ha_steve · July 21, 2020, 6:57pm

Mine seems to be working as expected. I don’t use the internal temperature (although it still reports) as it seems to be very wrong in mine (~5°C too high). I also don’t have the minimum change reporting and my interval is set to 300s (5 min).

config:

posixx · July 22, 2020, 1:20pm

I don’t know who to blame, HA plugin or OZW daemon, but after upgrading to OpenZWave 0.5.2 using supervisor my device names are lost again…

Veuchez · July 22, 2020, 9:34pm

The same thing happened to me too …