Not able to switch on/off fibaro lights "migrated" from old integration.

nicklaseklund commented 4 years ago

Home Assistant version: 106.2 (HA Core via docker-compose)
ozwdaemon version or add-on version: Latest version of ozwdeamon via docker (not compose)
homeassistant-zwave_mqtt version: v0.0.7 (newest) My Zwave network: Around 20 pcs of Fibaro Dimmer 2 and 2 pcs of Fibaro double switch

I've previously used integrated Zwave of HA and now migrated according to guides.

I have done following:

Removed Zwave-integrationen in HA
Installed MQTT server via docker-compose (toke/mosquitto)
Installed MQTT integrationen i HA and configured succesfully.
Started OZWDeamon via docker (and also added Network Key for Zwave (and added fine according to logs)).
Installed HACS
Installed Z-wave over MQTT (Pre-release) via HACS
Added Zwave-over-MQTT integration in HA.

The old devices are registered and shows up, and the OZW deamon are outputting data that looks good, what I can see.

When I start up no states of the lights are updated (except for one light). This one light, I can turn on and off without any issue. But no other lights are reacting to turning on/off via HA.

When I switch the light on via the wall switch the state is updated in HA. But still not able to control them via HA.

When I try to turn on a light following is outputted to the OZWdeamon:

[20200301 20:55:02.896 UTC] [qt.mqtt.connection.verbose] [debug]: void QMqttConnection::transportReadReady()
[20200301 20:55:02.896 UTC] [qt.mqtt.connection.verbose] [debug]: Received PUBLISH
[20200301 20:55:02.897 UTC] [qt.mqtt.connection.verbose] [debug]: Finalize PUBLISH: topic: QMqttTopicName("OpenZWave/1/command/setvalue/")  payloadLength: 39
[20200301 20:55:02.897 UTC] [ozw.mqtt.commands] [debug]: Got  "OpenZWave/1/command/setvalue/"  Message:  "{\"ValueIDKey\": 172589073, \"Value\": 255}"
[20200301 20:55:02.898 UTC] [ozw.mqtt.commands.setValue] [info]: Setting  172589073  to Value  QVariant(uint, 255)
[20200301 20:55:02.899 UTC] [ozw.values] [debug]: setData Called for Row 754  With Value QVariant(uint, 255)
[20200301 20:55:02.900 UTC] [ozw.values] [debug]: valueModel Changed! QTOZW_ValueIds::Value - 754 :  QVariant(uint, 255)
[20200301 20:55:02.900 UTC] [ozw.values] [debug]: valueModel Changed! vidKey: 172589073 QVariant(uint, 255)
[20200301 20:55:02.900 UTC] [ozw.library] [info]: Info - Node: 10 Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 255
[20200301 20:55:02.901 UTC] [ozw.library] [info]: Info - Node: 10 SwitchMultilevel::Set - Setting to level 255
[20200301 20:55:02.901 UTC] [ozw.library] [info]: Info - Node: 10   Duration: Default
[20200301 20:55:02.902 UTC] [ozw.library] [debug]: Detail - Node: 10 Queuing (Send) SwitchMultilevelCmd_Set (Node=10): 0x01, 0x0b, 0x00, 0x13, 0x0a, 0x04, 0x26, 0x01, 0xff, 0xff, 0x25, 0xef, 0x04
[20200301 20:55:02.902 UTC] [ozw.library] [debug]: Detail - Node: 10 Queuing (Send) SwitchMultilevelCmd_Get (Node=10): 0x01, 0x09, 0x00, 0x13, 0x0a, 0x02, 0x26, 0x02, 0x25, 0xf0, 0x1c
[20200301 20:55:02.903 UTC] [ozw.library] [debug]: Detail - Node: 0
[20200301 20:55:02.903 UTC] [ozw.library] [info]: Info - Node: 10 Sending (Send) message (Callback ID=0xef, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=10): 0x01, 0x0b, 0x00, 0x13, 0x0a, 0x04, 0x26, 0x01, 0xff, 0xff, 0x25, 0xef, 0x04
[20200301 20:55:02.903 UTC] [ozw.library] [info]: Info - Node: 10 Encrypted Flag is 0
[20200301 20:55:02.904 UTC] [qt.mqtt.connection] [debug]: qint32 QMqttConnection::sendControlPublish(const QMqttTopicName&, const QByteArray&, quint8, bool, const QMqttPublishProperties&) QMqttTopicName("OpenZWave/1/event/setvalue/")  Size: 51  bytes. QoS: 0  Retain: false
[20200301 20:55:02.904 UTC] [qt.mqtt.connection.verbose] [debug]: bool QMqttConnection::writePacketToTransport(const QMqttControlPacket&)  DataSize: 82
[20200301 20:55:02.905 UTC] [ozw.mqtt.commands] [info]: Processed Message for  "SetValue" :  "{\"ValueIDKey\": 172589073, \"Value\": 255}"
[20200301 20:55:02.906 UTC] [qt.mqtt.connection.verbose] [debug]: void QMqttConnection::transportReadReady()
[20200301 20:55:02.906 UTC] [qt.mqtt.connection.verbose] [debug]: Received PUBLISH
[20200301 20:55:02.907 UTC] [qt.mqtt.connection.verbose] [debug]: Finalize PUBLISH: topic: QMqttTopicName("OpenZWave/1/event/setvalue/")  payloadLength: 51
[20200301 20:55:02.933 UTC] [ozw.library] [debug]: Detail - Node: 10   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
[20200301 20:55:02.934 UTC] [ozw.library] [debug]: Detail - Node: 10   ZW_SEND_DATA delivered to Z-Wave stack
[20200301 20:55:02.949 UTC] [ozw.library] [debug]: Detail - Node: 10   Received: 0x01, 0x07, 0x00, 0x13, 0xef, 0x00, 0x00, 0x02, 0x06
[20200301 20:55:02.951 UTC] [ozw.library] [debug]: Detail - Node: 10   ZW_SEND_DATA Request with callback ID 0xef received (expected 0xef)
[20200301 20:55:02.952 UTC] [ozw.library] [info]: Info - Node: 10 Request RTT 48 Average Request RTT 47
[20200301 20:55:02.954 UTC] [ozw.library] [debug]: Detail - Node: 10   Expected callbackId was received
[20200301 20:55:02.955 UTC] [ozw.library] [debug]: Detail - Node: 10   Expected reply was received
[20200301 20:55:02.955 UTC] [ozw.library] [debug]: Detail - Node: 10   Message transaction complete
[20200301 20:55:02.956 UTC] [ozw.library] [debug]: Detail - Node: 0
[20200301 20:55:02.956 UTC] [ozw.library] [debug]: Detail - Node: 10 Removing current message
[20200301 20:55:02.956 UTC] [ozw.library] [debug]: Detail - Node: 0
[20200301 20:55:02.957 UTC] [ozw.library] [info]: Info - Node: 10 Sending (Send) message (Callback ID=0xf0, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=10): 0x01, 0x09, 0x00, 0x13, 0x0a, 0x02, 0x26, 0x02, 0x25, 0xf0, 0x1c
[20200301 20:55:02.957 UTC] [ozw.library] [info]: Info - Node: 10 Encrypted Flag is 0
[20200301 20:55:02.968 UTC] [ozw.library] [debug]: Detail - Node: 10   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
[20200301 20:55:02.969 UTC] [ozw.library] [debug]: Detail - Node: 10   ZW_SEND_DATA delivered to Z-Wave stack
[20200301 20:55:02.985 UTC] [ozw.library] [debug]: Detail - Node: 10   Received: 0x01, 0x07, 0x00, 0x13, 0xf0, 0x00, 0x00, 0x02, 0x19
[20200301 20:55:02.987 UTC] [ozw.library] [debug]: Detail - Node: 10   ZW_SEND_DATA Request with callback ID 0xf0 received (expected 0xf0)
[20200301 20:55:02.988 UTC] [ozw.library] [info]: Info - Node: 10 Request RTT 30 Average Request RTT 38
[20200301 20:55:02.989 UTC] [ozw.library] [debug]: Detail - Node: 10   Expected callbackId was received

mqtt_dump.txt

marcelveldt commented 4 years ago

Did you give the daemon enough time to properly interview all devices at first start ? It can take several hours until everything is initialized.

nicklaseklund commented 4 years ago

I gave it pretty long time. But I will try again and give it some more time to initialize everything.

nicklaseklund commented 4 years ago

@marcelveldt How can I tell if everything is initialized or not? I will leave it on during the day and see if things start working or not.

marcelveldt commented 4 years ago

It's ready a after a few hours. Just try to switch a light and see if it's reponding.

nicklaseklund commented 4 years ago

Well I left it running now for more than 9 hours, and still there is not change in functionallity. A few lights (the one I recently added, it seems) are working. But all old lights don't respond to trigger from HA. HA though updates the state when clicking on the wall switch.

nicklaseklund commented 4 years ago

The dimmers that don't work have only one switch, when checking this zwave mqtt integration. The dimmers that work have two switches (even though only one is used). I tried reenabling the second switch on every dimmer and started mqtt version again. But still only see one switch per dimmer, and no improvement in functionallity.

marcelveldt commented 4 years ago

I'm using Fibaro Dimmer 2 myself without any issues. What might be going on here is that you're trying to switch the 2nd instance of each dimmer ? Only Instance 1 will actually control your light.

Please also define what you mean with "old lights" ? You do know that it's not possible to migrate from the current Z-Wave implementation in Home Assistant to this new beta implementation ?

marcelveldt commented 4 years ago

@nicklaseklund reading your messages again I can only say that your configuration is now messed up as you tried converting from the old integration to new. You even disabled entities before properly testing what is what.

Please start fresh:

Remove Zwave MQTT integration from hass
Restart Hass (important, as it will cleanup leftover entities!)
Make sure you're using the latest version of the OpenZwave Daemon, if you're not on core-only HomeAssistant, you can use the addon on my personal supervisor repo.
Re-Add the Zwave MQTT integration in hass
Start testing again, do not yet disable any entities please, first test the basic behaviour and only disable entities that you don't use. --> As for the Fibary dimmers, be aware you will get 2 instances of the light entity. Only Instance 1 will be controlling your physical light, Instance 2 will only control whatever is attached to the Group 2 of the dimmer. In most cases you can disable this entity.

Please report back if you still have issues after trying this. For now I'll close this one.

cgarwood / homeassistant-zwave_mqtt

Not able to switch on/off fibaro lights "migrated" from old integration. #70