search

OpenZWave / open-zwave

a C++ library to control Z-Wave Networks via a USB Z-Wave Controller.
http://www.openzwave.net/
GNU Lesser General Public License v3.0
1.05k stars 916 forks source link

[bug] Cannot update values on SoundSwitch - Doorbell 6 #2085

Open varet80 opened 4 years ago

varet80 commented 4 years ago

Running 2.1.1 of zwave2mqtt and open-zwave 1.6.

Having an 3 days old Aeotec Doorbell 6. And with the latest firmware. Configuration of Volume/Type of sound seems to not work properly.

Example: WebUI apply settings for Tamper Alert type to Inactive (67-121-2-3)

Log output

2020-01-26T08:51:36.987Z z2m:App Zwave api call: setValue [ 67, 121, 2, 3, 'Inactive' ]
2020-01-26 08:51:36.989 Info, Node067, Value::Set - COMMAND_CLASS_SOUND_SWITCH - Default Tone - 3 - 2 - Inactive
2020-01-26 08:51:36.990 Detail, Node067, Setting Encryption Flag on Message For Command Class COMMAND_CLASS_MULTI_INSTANCE/CHANNEL
2020-01-26 08:51:36.990 Detail, Node067, Queuing (Send) MultiChannel Encapsulated (instance=2): SoundSwitchCmd_Tones_Config_Set (Node=67): 0x01, 0x0f, 0x00, 0x13, 0x43, 0x08, 0x60, 0x0d, 0x01, 0x01, 0x79, 0x05, 0x64, 0x06, 0x25, 0x29, 0xd7
2020-01-26 08:51:36.991 Detail,
2020-01-26 08:51:36.991 Info, Node067, Processing (Send) Nonce Request message (Callback ID=0x29, Expected Reply=0x04)
2020-01-26 08:51:36.991 Info, Node067, Sending (Send) message (Callback ID=0x02, Expected Reply=0x04) - Nonce_Get(MultiChannel Encapsulated (instance=2): SoundSwitchCmd_Tones_Config_Set) - 0x01, 0x09, 0x00, 0x13, 0x43, 0x02, 0x98, 0x40, 0x05, 0x02:
2020-01-26T08:51:36.991Z z2m:Zwave Success zwave api call setValue
2020-01-26 08:51:36.997 Detail, Node067,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-01-26 08:51:36.997 Detail, Node067,   ZW_SEND_DATA delivered to Z-Wave stack
2020-01-26 08:51:37.015 Detail, Node067,   Received: 0x01, 0x18, 0x00, 0x13, 0x02, 0x00, 0x00, 0x02, 0x00, 0xd8, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x2d
2020-01-26 08:51:37.015 Detail, Node067,   ZW_SEND_DATA Request with callback ID 0x02 received (expected 0x02)
2020-01-26 08:51:37.015 Info, Node067, Request RTT 24 Average Request RTT 166
2020-01-26 08:51:37.015 Detail, Node067, Extended TxStatus: Time: 20, Hops: 0, Rssi:  -40 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-01-26 08:51:37.033 Detail, Node067,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x43, 0x0a, 0x98, 0x80, 0x96, 0x4d, 0xa1, 0xc7, 0x34, 0x25, 0xc3, 0x87, 0xd8, 0x00, 0x88
2020-01-26 08:51:37.033 Info, Node067, Received SecurityCmd_NonceReport from node 67
2020-01-26 08:51:37.033 Info, Node067, Encrypted Flag is 1
2020-01-26 08:51:37.033 Info, Node067, Sending (Send) message (Callback ID=0x2a, Expected Reply=0x04) - MultiChannel Encapsulated (instance=2): SoundSwitchCmd_Tones_Config_Set (Node=67): 0x01, 0x0f, 0x00, 0x13, 0x43, 0x08, 0x60, 0x0d, 0x01, 0x01, 0x79, 0x05, 0x64, 0x06, 0x25, 0x2a, 0xd4
2020-01-26 08:51:37.042 Detail, Node067,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-01-26 08:51:37.043 Detail, Node067,   ZW_SEND_DATA delivered to Z-Wave stack
2020-01-26 08:51:37.063 Detail, Node067,   Received: 0x01, 0x18, 0x00, 0x13, 0x2a, 0x00, 0x00, 0x02, 0x00, 0xd8, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x05
2020-01-26 08:51:37.063 Detail, Node067,   ZW_SEND_DATA Request with callback ID 0x2a received (expected 0x2a)
2020-01-26 08:51:37.064 Info, Node067, Request RTT 73 Average Request RTT 119
2020-01-26 08:51:37.064 Detail, Node067, Extended TxStatus: Time: 20, Hops: 0, Rssi:  -40 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-01-26 08:51:37.065 Detail, Node067,   Expected callbackId was received
2020-01-26 08:51:46.992 Error, Node067, ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "MultiChannel Encapsulated (instance=2): SoundSwitchCmd_Tones_Config_Set (Node=67): 0x01, 0x0f, 0x00, 0x13, 0x43, 0x08, 0x60, 0x0d, 0x01, 0x01, 0x79, 0x05, 0x64, 0x06, 0x25, 0x2a, 0xd4"
2020-01-26 08:51:46.992 Detail, Node067, Removing current message
2020-01-26 08:51:46.992 Detail, Node067, Notification: Notification - TimeOut
2020-01-26T08:51:46.994Z z2m:Zwave Notification from node 67: Notification - TimeOut (1)

example of volume update doorbell 1:

2020-01-26T08:53:32.704Z z2m:App Zwave api call: setValue [ 67, 121, 3, 2, '6' ]
2020-01-26 08:53:32.707 Info, Node067, Value::Set - COMMAND_CLASS_SOUND_SWITCH - Volume - 2 - 3 - 6
2020-01-26 08:53:32.707 Detail, Node067, Setting Encryption Flag on Message For Command Class COMMAND_CLASS_MULTI_INSTANCE/CHANNEL
2020-01-26 08:53:32.707 Detail, Node067, Queuing (Send) MultiChannel Encapsulated (instance=3): SoundSwitchCmd_Tones_Config_Set (Node=67): 0x01, 0x0f, 0x00, 0x13, 0x43, 0x08, 0x60, 0x0d, 0x01, 0x02, 0x79, 0x05, 0x64, 0x11, 0x25, 0x2b, 0xc1
2020-01-26T08:53:32.708Z z2m:Zwave Success zwave api call setValue
2020-01-26 08:53:32.709 Detail,
2020-01-26 08:53:32.711 Info, Node067, Processing (Send) Nonce Request message (Callback ID=0x2b, Expected Reply=0x04)
2020-01-26 08:53:32.712 Info, Node067, Sending (Send) message (Callback ID=0x02, Expected Reply=0x04) - Nonce_Get(MultiChannel Encapsulated (instance=3): SoundSwitchCmd_Tones_Config_Set) - 0x01, 0x09, 0x00, 0x13, 0x43, 0x02, 0x98, 0x40, 0x05, 0x02:
2020-01-26 08:53:32.719 Detail, Node067,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-01-26 08:53:32.719 Detail, Node067,   ZW_SEND_DATA delivered to Z-Wave stack
2020-01-26 08:53:32.737 Detail, Node067,   Received: 0x01, 0x18, 0x00, 0x13, 0x02, 0x00, 0x00, 0x02, 0x00, 0xda, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x2f
2020-01-26 08:53:32.738 Detail, Node067,   ZW_SEND_DATA Request with callback ID 0x02 received (expected 0x02)
2020-01-26 08:53:32.738 Info, Node067, Request RTT 24 Average Request RTT 71
2020-01-26 08:53:32.738 Detail, Node067, Extended TxStatus: Time: 20, Hops: 0, Rssi:  -38 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-01-26 08:53:32.755 Detail, Node067,   Received: 0x01, 0x12, 0x00, 0x04, 0x00, 0x43, 0x0a, 0x98, 0x80, 0x02, 0x29, 0xe1, 0x1a, 0xd9, 0x39, 0x1e, 0x35, 0xda, 0x00, 0x79
2020-01-26 08:53:32.756 Info, Node067, Received SecurityCmd_NonceReport from node 67
2020-01-26 08:53:32.756 Info, Node067, Encrypted Flag is 1
2020-01-26 08:53:32.756 Info, Node067, Sending (Send) message (Callback ID=0x2c, Expected Reply=0x04) - MultiChannel Encapsulated (instance=3): SoundSwitchCmd_Tones_Config_Set (Node=67): 0x01, 0x0f, 0x00, 0x13, 0x43, 0x08, 0x60, 0x0d, 0x01, 0x02, 0x79, 0x05, 0x64, 0x11, 0x25, 0x2c, 0xc6
2020-01-26 08:53:32.766 Detail, Node067,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-01-26 08:53:32.766 Detail, Node067,   ZW_SEND_DATA delivered to Z-Wave stack
2020-01-26 08:53:32.786 Detail, Node067,   Received: 0x01, 0x18, 0x00, 0x13, 0x2c, 0x00, 0x00, 0x02, 0x00, 0xda, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x03, 0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0x00, 0x00, 0x01
2020-01-26 08:53:32.787 Detail, Node067,   ZW_SEND_DATA Request with callback ID 0x2c received (expected 0x2c)
2020-01-26 08:53:32.787 Info, Node067, Request RTT 73 Average Request RTT 72
2020-01-26 08:53:32.787 Detail, Node067, Extended TxStatus: Time: 20, Hops: 0, Rssi:  -38 --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Last Working Route, Route: 0 0 0 0, RouteSpeed: 100K, RouteTries: 1, FailedLinkFrom: 0, FailedLinkTo: 0
2020-01-26 08:53:32.789 Detail, Node067,   Expected callbackId was received
2020-01-26 08:53:42.714 Error, Node067, ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "MultiChannel Encapsulated (instance=3): SoundSwitchCmd_Tones_Config_Set (Node=67): 0x01, 0x0f, 0x00, 0x13, 0x43, 0x08, 0x60, 0x0d, 0x01, 0x02, 0x79, 0x05, 0x64, 0x11, 0x25, 0x2c, 0xc6"
2020-01-26 08:53:42.715 Detail, Node067, Removing current message
2020-01-26 08:53:42.715 Detail, Node067, Notification: Notification - TimeOut
2020-01-26T08:53:42.717Z z2m:Zwave Notification from node 67: Notification - TimeOut (1)


Also should soundSwitch be a parameter accessible by the automation and not a configuration param, meaning switch/adjustment instead of configuration?
It is a soundswitch ( in light similar is an RGB light or dimmer right?)
varet80 commented 4 years ago

Tested it also on the latest Domoticz (4.110 with OZW 1.6) same issue

varet80 commented 4 years ago

Testing with qt-openzwave running 1.6.1019 also similar results

from my setup the node value id is:

{
    "Label": "Doorbell 1 Volume",
    "Value": 100,
    "Units": "",
    "Min": 0,
    "Max": 255,
    "Type": "Byte",
    "Instance": 3,
    "CommandClass": "COMMAND_CLASS_SOUND_SWITCH",
    "Index": 2,
    "Node": 74,
    "Genre": "Config",
    "Help": "The Volume to play the tone at",
    "ValueIDKey": 562951205306417,
    "ReadOnly": false,
    "WriteOnly": false,
    "ValueSet": false,
    "ValuePolled": false,
    "ChangeVerified": false,
    "Event": "valueAdded",
    "TimeStamp": 1580115716
}

used the command:

OpenZWave/1/command/setvalue/
{ "ValueIDKey": 562951205306417, "Value": 10 }

Error similar:

[20200127 9:09:24.200 UTC] [qt.mqtt.connection.verbose] [debug]: Finalize PUBLISH: topic: QMqttTopicName("OpenZWave/1/node/73/statistics/")  payloadLength: 809
[20200127 9:09:24.200 UTC] [qt.mqtt.connection.verbose] [debug]: Received PUBLISH
[20200127 9:09:24.200 UTC] [qt.mqtt.connection.verbose] [debug]: Finalize PUBLISH: topic: QMqttTopicName("OpenZWave/1/node/74/statistics/")  payloadLength: 817
[20200127 9:09:29.725 UTC] [ozw.library] [critical]: Error - Node: 74 ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "MultiChannel Encapsulated (instance=3): SoundSwitchCmd_Tones_Config_Set (Node=74): 0x01, 0x0f, 0x00, 0x13, 0x4a, 0x08, 0x60, 0x0d, 0x01, 0x02, 0x79, 0x05, 0x64, 0x11, 0x25, 0xb0, 0x53"
[20200127 9:09:29.726 UTC] [ozw.library] [debug]: Detail - Node: 74 Removing current message
[20200127 9:09:29.727 UTC] [ozw.library] [debug]: Detail - Node: 74 Notification: Notification - TimeOut
[20200127 9:09:29.728 UTC] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_MsgTimeout
[20200127 9:09:29.729 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification
[20200127 9:09:29.730 UTC] [qt.mqtt.connection] [debug]: qint32 QMqttConnection::sendControlPublish(const QMqttTopicName&, const QByteArray&, quint8, bool, const QMqttPublishProperties&) QMqttTopicName("OpenZWave/1/event/notification/")  Size: 92  bytes. QoS: 0  Retain: false
[20200127 9:09:29.730 UTC] [qt.mqtt.connection.verbose] [debug]: bool QMqttConnection::writePacketToTransport(const QMqttControlPacket&)  DataSize: 127
[20200127 9:09:29.732 UTC] [qt.mqtt.connection.verbose] [debug]: void QMqttConnection::transportReadReady()
[20200127 9:09:29.733 UTC] [qt.mqtt.connection.verbose] [debug]: Received PUBLISH
[20200127 9:09:29.734 UTC] [qt.mqtt.connection.verbose] [debug]: Finalize PUBLISH: topic: QMqttTopicName("OpenZWave/1/event/notification/")  payloadLength: 92
[20200127 9:09:53.185 UTC] [qt.mqtt.connection] [debug]: bool QMqttConnection::sendControlPingRequest()
[20200127 9:09:53.186 UTC] [qt.mqtt.connection.verbose] [debug]: bool QMqttConnection::writePacketToTransport(const QMqttControlPacket&)  DataSize: 2
[20200127 9:09:53.187 UTC] [qt.mqtt.connection.verbose] [debug]: void QMqttConnection::transportReadReady()
[20200127 9:09:53.188 UTC] [qt.mqtt.connection.verbose] [debug]: Received PINGRESP
[20200127 9:09:53.189 UTC] [qt.mqtt.connection.verbose] [debug]: Finalize PINGRESP
[20200127 9:09:53.189 UTC] [ozw.mqtt.publisher] [debug]: "Mon Jan 27 09:09:53 2020 PingResponse\n"

Also when joining encrypted the Ringtone values are broken example:

{
    "Label": "Doorbell 1 Tones",
    "Value": {
        "List": [
            {
                "Value": 0,
                "Label": "Inactive"
            },
            {
                "Value": 1,
                "Label": "01 Ding Dong (5 sec)"
            },
            {
                "Value": 2,
                "Label": "02 Ding Dong Tub��]Q (9 sec)"
            },
            {
                "Value": 3,
                "Label": "03 Traditional A�\u001E��n�\u0010�lD����P (10 sec)"
            },
            {
                "Value": 4,
                "Label": "04 Electric Aparf�\u0003�7�co�?�^ (1 sec)"
            },
            {
                "Value": 5,
                "Label": "05 Westminster C(\r\u0018_� (12 sec)"
            },
            {
                "Value": 6,
                "Label": "06 Chimes (7 sec)"
            },
            {
                "Value": 7,
                "Label": "07 Cuckoo (31 sec)"
            },
            {
                "Value": 8,
                "Label": "08 Traditional B\u0013>\u0007 (6 sec)"
            },
            {
                "Value": 9,
                "Label": "09 Smoke Alarm 1 (11 sec)"
            },
            {
                "Value": 10,
                "Label": "10 Smoke Alarm 2 (5 sec)"
            },
            {
                "Value": 11,
                "Label": "11 Fire Evacuati��]�\u0007�\\)  (35 sec)"
            },
            {
                "Value": 12,
                "Label": "12 CO Sensor (4 sec)"
            },
            {
                "Value": 13,
                "Label": "13 Klaxon (6 sec)"
            },
            {
                "Value": 14,
                "Label": "14 Deep Klaxon (40 sec)"
            },
            {
                "Value": 15,
                "Label": "15 Warning Tone (37 sec)"
            },
            {
                "Value": 16,
                "Label": "16 Tornado Siren (45 sec)"
            },
            {
                "Value": 17,
                "Label": "17 Alarm (35 sec)"
            },
            {
                "Value": 18,
                "Label": "18 Deep Alarm (62 sec)"
            },
            {
                "Value": 19,
                "Label": "19 Alarm Archang�� (15 sec)"
            },
            {
                "Value": 20,
                "Label": "20 Alarm Shrill (7 sec)"
            },
            {
                "Value": 21,
                "Label": "21 Digital Siren (8 sec)"
            },
            {
                "Value": 22,
                "Label": "22 Alert Series (63 sec)"
            },
            {
                "Value": 23,
                "Label": "23 Ship Bell (3 sec)"
            },
            {
                "Value": 24,
                "Label": "24 Clock Buzzer (9 sec)"
            },
            {
                "Value": 25,
                "Label": "25 Christmas Tre\b (3 sec)"
            },
            {
                "Value": 26,
                "Label": "26 Gong (11 sec)"
            },
            {
                "Value": 27,
                "Label": "27 Single Bell TmM� (0 sec)"
            },
            {
                "Value": 28,
                "Label": "28 Tonal Pulse (11 sec)"
            },
            {
                "Value": 29,
                "Label": "29 Upwards Tone (2 sec)"
            },
            {
                "Value": 30,
                "Label": "30 Door Open (27 sec)"
            },
            {
                "Value": 255,
                "Label": "Default Tone"
            }
        ],
        "Selected": "Inactive"
    },
    "Units": "",
    "Min": 0,
    "Max": 0,
    "Type": "List",
    "Instance": 3,
    "CommandClass": "COMMAND_CLASS_SOUND_SWITCH",
    "Index": 1,
    "Node": 74,
    "Genre": "User",
    "Help": "A List of Available Tones on the Device",
    "ValueIDKey": 281476224401460,
    "ReadOnly": false,
    "WriteOnly": false,
    "ValueSet": false,
    "ValuePolled": false,
    "ChangeVerified": false,
    "Event": "valueAdded",
    "TimeStamp": 1580115813
}

when insecure they show without special characters.

Fishwaldo commented 4 years ago

hrm... I'll have to pull mine out. It was working (but I'll check firmware versions). Maybe you can open a support case with Aeotec and ask them if it works.

As far as the "corruption" with secure/non-secure - That doesn't make much sense. In the OZW Log file are the labels "corrupt" as well? what about ozwcache*.xml file etc.

varet80 commented 4 years ago

The sound types names, seem to come from the doorbell itself, xml doesn't have any reference to these unzer zw162.xml

Unencrypted the sound names are ok, S0 encryption not ok.

Tested with 3 qt-openzwave,zwave2mqtt and domoticz beta

Fishwaldo commented 4 years ago

The names should be saved in ozwcache file after inclusion. Please check that and compare between encrypted/non-encrypted.

varet80 commented 4 years ago

The names should be saved in ozwcache file after inclusion. Please check that and compare between encrypted/non-encrypted.

Will do later today, and Update this thread. should I post also inclusion logs?

varet80 commented 4 years ago

Including a Secure inclusion, later when home I will re include with insecure inclusion ozwcache_secure.txt

varet80 commented 4 years ago

attaching Inclusion log from qt-openzwave 2.1.1 insecure inclusion doorbell6 - insecure.txt

Ozwcache of node insecure: doorbell6 - insecure ozwcache.txt

@Fishwaldo Are these useful? Thanks, Vassilis

varet80 commented 4 years ago

hrm... I'll have to pull mine out. It was working (but I'll check firmware versions). Maybe you can open a support case with Aeotec and ask them if it works.

From my device data I do see Version is 1.04?

varet80 commented 4 years ago

Tested the changes with z-way server (z-wave.me) and the changes work without issues. I suspect is not the device

from Z-way server logs:


[2020-01-29 16:06:24.346] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6 = Empty
[2020-01-29 16:06:24.346] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6.status = Empty
[2020-01-29 16:06:24.346] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6.duration = Empty
[2020-01-29 16:06:24.347] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6.moreStatusUpdates = Empty
[2020-01-29 16:06:24.347] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6.lastEvent = Empty
[2020-01-29 16:06:24.347] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6.status = 255 (0x000000ff)
[2020-01-29 16:06:24.348] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6.duration = 0 (0x00000000)
[2020-01-29 16:06:24.348] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6.moreStatusUpdates = False
[2020-01-29 16:06:24.348] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6.lastEvent = 823177 (0x000c8f89)
[2020-01-29 16:06:24.349] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.6 = Empty
[2020-01-29 16:06:24.349] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.lastSession = 6 (0x00000006)
[2020-01-29 16:06:24.350] [I] [zway] Adding job: SoundSwitch Configuration Set
[2020-01-29 16:06:24.350] [D] [zway] SENDING (cb 0xd8): ( 01 13 00 13 4C 0C 60 0D 00 03 6C 01 86 04 79 05 00 00 25 D8 BF )
[2020-01-29 16:06:24.353] [D] [zway] RECEIVED ACK
[2020-01-29 16:06:24.357] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-01-29 16:06:24.358] [D] [zway] SENT ACK
[2020-01-29 16:06:24.358] [D] [zway] Delivered to Z-Wave stack
[2020-01-29 16:06:24.381] [D] [zway] RECEIVED: ( 01 18 00 13 D8 00 00 02 00 DD 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 F2 )
[2020-01-29 16:06:24.382] [D] [zway] SENT ACK
[2020-01-29 16:06:24.383] [I] [zway] Job 0x13 (SoundSwitch Configuration Set): Delivered
[2020-01-29 16:06:24.383] [D] [zway] SendData Response with callback 0xd8 received: received by recipient
[2020-01-29 16:06:24.384] [D] [zway] SETDATA devices.76.data.lastSendInternal = **********
[2020-01-29 16:06:24.384] [D] [zway] SETDATA devices.76.data.lastSend = 823181 (0x000c8f8d)
[2020-01-29 16:06:24.385] [D] [zway] Job 0x13 (SoundSwitch Configuration Set): success
[2020-01-29 16:06:24.385] [I] [zway] Waiting for job reply: SoundSwitch Configuration Set
[2020-01-29 16:06:24.412] [D] [zway] RECEIVED: ( 01 11 00 04 00 4C 09 60 0D 03 00 6C 02 06 FF 00 DD 00 8B )
[2020-01-29 16:06:24.413] [D] [zway] SENT ACK
[2020-01-29 16:06:24.413] [D] [zway] SETDATA devices.76.data.lastReceived = 0 (0x00000000)
[2020-01-29 16:06:24.414] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6 = Empty
[2020-01-29 16:06:24.414] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6.status = Empty
[2020-01-29 16:06:24.414] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6.duration = Empty
[2020-01-29 16:06:24.415] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6.moreStatusUpdates = Empty
[2020-01-29 16:06:24.415] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6.lastEvent = Empty
[2020-01-29 16:06:24.416] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6.status = 255 (0x000000ff)
[2020-01-29 16:06:24.416] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6.duration = 0 (0x00000000)
[2020-01-29 16:06:24.416] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6.moreStatusUpdates = False
[2020-01-29 16:06:24.417] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6.lastEvent = 823184 (0x000c8f90)
[2020-01-29 16:06:24.417] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.6 = Empty
[2020-01-29 16:06:24.418] [D] [zway] Received reply on job (SoundSwitch Configuration Set)
[2020-01-29 16:06:24.418] [I] [zway] Node 76:3 CC Supervision: Setter packet was confirmed: SoundSwitch Configuration Set
[2020-01-29 16:06:24.418] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultVolume = 0 (0x00000000)
[2020-01-29 16:06:24.419] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultTone = 2 (0x00000002)
[2020-01-29 16:06:24.436] [I] [core] Notification: device-info (device-status): {"dev":"Volume (76.3)","l":"0%","location":""}
[2020-01-29 16:06:24.524] [D] [zway] RECEIVED: ( 01 10 00 04 00 4C 08 60 0D 03 00 79 07 00 02 DD 00 60 )
[2020-01-29 16:06:24.525] [D] [zway] SENT ACK
[2020-01-29 16:06:24.526] [D] [zway] SETDATA devices.76.data.lastReceived = 0 (0x00000000)
[2020-01-29 16:06:24.526] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultVolume = 0 (0x00000000)
[2020-01-29 16:06:24.527] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultTone = 2 (0x00000002)
[2020-01-29 16:06:26.528] [D] [zway] Job 0x13: deleted from queue

[2020-01-29 16:06:35.656] [I] [zway] Adding job: SoundSwitch Configuration Get
[2020-01-29 16:06:35.662] [D] [zway] SENDING (cb 0xd9): ( 01 0D 00 13 4C 06 60 0D 00 03 79 06 25 D9 46 )
[2020-01-29 16:06:35.664] [D] [zway] RECEIVED ACK
[2020-01-29 16:06:35.668] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-01-29 16:06:35.668] [D] [zway] SENT ACK
[2020-01-29 16:06:35.668] [D] [zway] Delivered to Z-Wave stack
[2020-01-29 16:06:35.693] [D] [zway] RECEIVED: ( 01 18 00 13 D9 00 00 02 00 DD 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 F3 )
[2020-01-29 16:06:35.693] [D] [zway] SENT ACK
[2020-01-29 16:06:35.693] [I] [zway] Job 0x13 (SoundSwitch Configuration Get): Delivered
[2020-01-29 16:06:35.694] [D] [zway] SendData Response with callback 0xd9 received: received by recipient
[2020-01-29 16:06:35.694] [D] [zway] SETDATA devices.76.data.lastSendInternal = **********
[2020-01-29 16:06:35.694] [D] [zway] SETDATA devices.76.data.lastSend = 824297 (0x000c93e9)
[2020-01-29 16:06:35.694] [D] [zway] Job 0x13 (SoundSwitch Configuration Get): success
[2020-01-29 16:06:35.694] [I] [zway] Waiting for job reply: SoundSwitch Configuration Get
[2020-01-29 16:06:35.704] [D] [zway] RECEIVED: ( 01 10 00 04 00 4C 08 60 0D 03 00 79 07 00 02 DB 00 66 )
[2020-01-29 16:06:35.704] [D] [zway] SENT ACK
[2020-01-29 16:06:35.704] [D] [zway] SETDATA devices.76.data.lastReceived = 0 (0x00000000)
[2020-01-29 16:06:35.705] [D] [zway] Received reply on job (SoundSwitch Configuration Get)
[2020-01-29 16:06:35.705] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultVolume = 0 (0x00000000)
[2020-01-29 16:06:35.705] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultTone = 2 (0x00000002)
[2020-01-29 16:06:37.830] [D] [zway] Job 0x13: deleted from queue
[2020-01-29 16:06:38.620] [D] [zway] Job 0x3b: deleted from queue
[2020-01-29 16:06:48.361] [I] [zway] Adding job: Get background noise level
[2020-01-29 16:06:48.371] [D] [zway] SENDING: ( 01 03 00 3B C7 )
[2020-01-29 16:06:48.372] [D] [zway] RECEIVED ACK
[2020-01-29 16:06:48.373] [D] [zway] RECEIVED: ( 01 05 01 3B A2 7D 1F )
[2020-01-29 16:06:48.373] [D] [zway] SENT ACK
[2020-01-29 16:06:48.373] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = 162 (0x000000a2)
[2020-01-29 16:06:48.373] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = 125 (0x0000007d)
[2020-01-29 16:06:48.374] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = 127 (0x0000007f)
[2020-01-29 16:06:48.374] [I] [zway] Job 0x3b (Get background noise level): RSSI Ch#1: -94 dBm, Ch#2: too low, Ch#3: invalid
[2020-01-29 16:06:48.374] [D] [zway] Job 0x3b (Get background noise level): success
[2020-01-29 16:06:48.374] [I] [zway] Removing job: Get background noise level
[2020-01-29 16:07:05.405] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7 = Empty
[2020-01-29 16:07:05.405] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7.status = Empty
[2020-01-29 16:07:05.405] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7.duration = Empty
[2020-01-29 16:07:05.405] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7.moreStatusUpdates = Empty
[2020-01-29 16:07:05.405] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7.lastEvent = Empty
[2020-01-29 16:07:05.406] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7.status = 255 (0x000000ff)
[2020-01-29 16:07:05.406] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7.duration = 0 (0x00000000)
[2020-01-29 16:07:05.406] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7.moreStatusUpdates = False
[2020-01-29 16:07:05.406] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7.lastEvent = 827228 (0x000c9f5c)
[2020-01-29 16:07:05.407] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.7 = Empty
[2020-01-29 16:07:05.407] [D] [zway] SETDATA devices.76.instances.0.commandClasses.108.data.lastSession = 7 (0x00000007)
[2020-01-29 16:07:05.407] [I] [zway] Adding job: SoundSwitch Configuration Set
[2020-01-29 16:07:05.409] [D] [zway] SENDING (cb 0xda): ( 01 13 00 13 4C 0C 60 0D 00 03 6C 01 87 04 79 05 01 00 25 DA BD )
[2020-01-29 16:07:05.412] [D] [zway] RECEIVED ACK
[2020-01-29 16:07:05.416] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-01-29 16:07:05.416] [D] [zway] SENT ACK
[2020-01-29 16:07:05.416] [D] [zway] Delivered to Z-Wave stack
[2020-01-29 16:07:05.442] [D] [zway] RECEIVED: ( 01 18 00 13 DA 00 00 02 00 DB 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 F6 )
[2020-01-29 16:07:05.442] [D] [zway] SENT ACK
[2020-01-29 16:07:05.442] [I] [zway] Job 0x13 (SoundSwitch Configuration Set): Delivered
[2020-01-29 16:07:05.443] [D] [zway] SendData Response with callback 0xda received: received by recipient
[2020-01-29 16:07:05.443] [D] [zway] SETDATA devices.76.data.lastSendInternal = **********
[2020-01-29 16:07:05.443] [D] [zway] SETDATA devices.76.data.lastSend = 827232 (0x000c9f60)
[2020-01-29 16:07:05.443] [D] [zway] Job 0x13 (SoundSwitch Configuration Set): success
[2020-01-29 16:07:05.443] [I] [zway] Waiting for job reply: SoundSwitch Configuration Set
[2020-01-29 16:07:05.462] [D] [zway] RECEIVED: ( 01 11 00 04 00 4C 09 60 0D 03 00 6C 02 07 FF 00 DB 00 8C )
[2020-01-29 16:07:05.462] [D] [zway] SENT ACK
[2020-01-29 16:07:05.463] [D] [zway] SETDATA devices.76.data.lastReceived = 0 (0x00000000)
[2020-01-29 16:07:05.463] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7 = Empty
[2020-01-29 16:07:05.463] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7.status = Empty
[2020-01-29 16:07:05.463] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7.duration = Empty
[2020-01-29 16:07:05.463] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7.moreStatusUpdates = Empty
[2020-01-29 16:07:05.463] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7.lastEvent = Empty
[2020-01-29 16:07:05.463] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7.status = 255 (0x000000ff)
[2020-01-29 16:07:05.463] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7.duration = 0 (0x00000000)
[2020-01-29 16:07:05.464] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7.moreStatusUpdates = False
[2020-01-29 16:07:05.464] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7.lastEvent = 827234 (0x000c9f62)
[2020-01-29 16:07:05.464] [D] [zway] SETDATA devices.76.instances.3.commandClasses.108.data.7 = Empty
[2020-01-29 16:07:05.464] [D] [zway] Received reply on job (SoundSwitch Configuration Set)
[2020-01-29 16:07:05.464] [I] [zway] Node 76:3 CC Supervision: Setter packet was confirmed: SoundSwitch Configuration Set
[2020-01-29 16:07:05.464] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultVolume = 1 (0x00000001)
[2020-01-29 16:07:05.464] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultTone = 2 (0x00000002)
[2020-01-29 16:07:05.483] [I] [core] Notification: device-info (device-status): {"dev":"Volume (76.3)","l":"1%","location":""}
[2020-01-29 16:07:05.523] [D] [zway] RECEIVED: ( 01 10 00 04 00 4C 08 60 0D 03 00 79 07 01 02 DB 00 67 )
[2020-01-29 16:07:05.524] [D] [zway] SENT ACK
[2020-01-29 16:07:05.524] [D] [zway] SETDATA devices.76.data.lastReceived = 0 (0x00000000)
[2020-01-29 16:07:05.524] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultVolume = 1 (0x00000001)
[2020-01-29 16:07:05.524] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultTone = 2 (0x00000002)
[2020-01-29 16:07:07.585] [D] [zway] Job 0x13: deleted from queue
[2020-01-29 16:07:08.608] [D] [zway] Job 0x3b: deleted from queue

[2020-01-29 16:07:14.221] [I] [zway] Adding job: SoundSwitch Configuration Get
[2020-01-29 16:07:14.229] [D] [zway] SENDING (cb 0xdb): ( 01 0D 00 13 4C 06 60 0D 00 03 79 06 25 DB 44 )
[2020-01-29 16:07:14.231] [D] [zway] RECEIVED ACK
[2020-01-29 16:07:14.235] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2020-01-29 16:07:14.235] [D] [zway] SENT ACK
[2020-01-29 16:07:14.236] [D] [zway] Delivered to Z-Wave stack
[2020-01-29 16:07:14.259] [D] [zway] RECEIVED: ( 01 18 00 13 DB 00 00 02 00 DB 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 F7 )
[2020-01-29 16:07:14.259] [D] [zway] SENT ACK
[2020-01-29 16:07:14.260] [I] [zway] Job 0x13 (SoundSwitch Configuration Get): Delivered
[2020-01-29 16:07:14.260] [D] [zway] SendData Response with callback 0xdb received: received by recipient
[2020-01-29 16:07:14.260] [D] [zway] SETDATA devices.76.data.lastSendInternal = **********
[2020-01-29 16:07:14.260] [D] [zway] SETDATA devices.76.data.lastSend = 828102 (0x000ca2c6)
[2020-01-29 16:07:14.260] [D] [zway] Job 0x13 (SoundSwitch Configuration Get): success
[2020-01-29 16:07:14.260] [I] [zway] Waiting for job reply: SoundSwitch Configuration Get
[2020-01-29 16:07:14.279] [D] [zway] RECEIVED: ( 01 10 00 04 00 4C 08 60 0D 03 00 79 07 01 02 DB 00 67 )
[2020-01-29 16:07:14.279] [D] [zway] SENT ACK
[2020-01-29 16:07:14.280] [D] [zway] SETDATA devices.76.data.lastReceived = 0 (0x00000000)
[2020-01-29 16:07:14.280] [D] [zway] Received reply on job (SoundSwitch Configuration Get)
[2020-01-29 16:07:14.280] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultVolume = 1 (0x00000001)
[2020-01-29 16:07:14.280] [D] [zway] SETDATA devices.76.instances.3.commandClasses.121.data.defaultTone = 2 (0x00000002)
[2020-01-29 16:07:16.400] [D] [zway] Job 0x13: deleted from queue
[2020-01-29 16:07:16.959] [I] [zway] Adding job: Get statistics gathered by the Z-Wave protocol
[2020-01-29 16:07:16.967] [D] [zway] SENDING: ( 01 03 00 3A C6 )
[2020-01-29 16:07:16.969] [D] [zway] RECEIVED ACK
[2020-01-29 16:07:16.978] [D] [zway] RECEIVED: ( 01 0F 01 3A 01 7F 00 00 01 8D 00 01 00 00 00 00 38 )
[2020-01-29 16:07:16.978] [D] [zway] SENT ACK
[2020-01-29 16:07:16.978] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = 383 (0x0000017f)
[2020-01-29 16:07:16.978] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = 0 (0x00000000)
[2020-01-29 16:07:16.979] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = 397 (0x0000018d)
[2020-01-29 16:07:16.979] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = 1 (0x00000001)
[2020-01-29 16:07:16.979] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = 0 (0x00000000)
[2020-01-29 16:07:16.979] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = 0 (0x00000000)
[2020-01-29 16:07:16.979] [I] [zway] Job 0x3a (Get statistics gathered by the Z-Wave protocol): Done
[2020-01-29 16:07:16.979] [D] [zway] Job 0x3a (Get statistics gathered by the Z-Wave protocol): success
[2020-01-29 16:07:16.979] [I] [zway] Removing job: Get statistics gathered by the Z-Wave protocol
[2020-01-29 16:07:18.362] [I] [zway] Adding job: Get background noise level
Fishwaldo commented 4 years ago

Ok. It does help. Truly bizarre! (The corruption that is). Looks like a off by 1 error in the decoding of packets or I’m not zeroing out a buffer somewhere.

I’m traveling right now and won’t be able to get to this till next week at the earliest. Leave it with me to investigate

varet80 commented 4 years ago

Cool if you want me to switch over my installations I am happy to provide more logs/support/tests.

Thank you for the awesome library and work!

Fishwaldo commented 4 years ago

In the latest commits I tidied up the SoundSwitch. I’ve also realized I had firmware 1.03 and I’ve asked for 1.04.

I couldn’t duplicate the corruption but in 1.03 the tone names are truncated and not like your 1.04 so when I can upgrade I’ll test again.

varet80 commented 4 years ago

The names is the least of my issue, the inability to configure it was the biggest. Insecure or secure same issue I will compile the latest commits and try.

aretakisv commented 4 years ago

In the latest commits I tidied up the SoundSwitch. I’ve also realized I had firmware 1.03 and I’ve asked for 1.04.

I couldn’t duplicate the corruption but in 1.03 the tone names are truncated and not like your 1.04 so when I can upgrade I’ll test again.

I can confirm insecure works! in secure mode it doesn't recognise everything and corruption still there.

aretakisv commented 4 years ago

@Fishwaldo I get this error once I restart my gateway and request device info.. this way I loose all my CC 121 settings

Error, Node011, Received a MultiChannelEncap for endpoint 2 for Command Class 121, which we can't find
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08 18:35:11.533 Detail, Node011, Notification: ValueChanged
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08 18:35:11.533 Detail, Node011, Notification: ValueChanged
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08T18:35:11.534Z z2m:Zwave zwave node 11: changed: 113-1-0:Alarm Type:0 -> 0
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08T18:35:11.535Z z2m:Zwave zwave node 11: changed: 113-1-1:Alarm Level:0 -> 0
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08T18:35:11.537Z z2m:Zwave zwave node 11: changed: 113-1-2:SourceNodeId:0 -> 0
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08T18:35:11.539Z z2m:Zwave zwave node 11: changed: 113-1-10:Burglar:9 -> 9
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08 18:35:11.610 Detail, Node011,   Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x0b, 0x07, 0x60, 0x0d, 0x02, 0x01, 0x79, 0x0a, 0x00, 0xe7
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08 18:35:11.610 Detail,
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08 18:35:11.611 Error, Node011, Received a MultiChannelEncap for endpoint 2 for Command Class 121, which we can't find
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08T18:35:45.850Z z2m:App User disconnected qian6EK4fdpTZHBzAAAI
zwave2mqtt2-667f97b6f7-nwl7z zwave2mqtt 2020-02-08T18:35:48.928Z z2m:App New connection s_zjHHQzxGs3KvFSAAAJ
Fishwaldo commented 4 years ago

I need the full logs to diagnose that.

Fishwaldo commented 4 years ago

This is on 1.03 for me. Works fine:

2020-02-09 12:17:44.976 Detail, Node029, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x1d, 0x07, 0x60, 0x0d, 0x03, 0x01, 0x79, 0x0a, 0x01, 0xf1 2020-02-09 12:17:44.976 Detail, 2020-02-09 12:17:44.977 Info, Node029, Received a MultiChannelEncap from node 29, endpoint 3 for Command Class COMMAND_CLASS_SOUND_SWITCH 2020-02-09 12:17:44.977 Info, Node029, Received SoundSwitch Tone Play report: 1 2020-02-09 12:17:44.977 Detail, Node029, Refreshed Value: old value=0, new value=1, type=list 2020-02-09 12:17:44.977 Detail, Node029, Changes to this value are not verified 2020-02-09 12:17:44.977 Detail, Node029, Notification: ValueChanged CC: COMMAND_CLASS_SOUND_SWITCH Instance: 3 Index: 1 2020-02-09 12:17:44.977 Info, Notification: Value Changed Home 0xdd12ee37 Node 29 Genre user Class SOUND SWITCH Instance 3 Index 1 Type list IsSet True ValueAsString 01DING~1 (5 sec) List Item: 1 Selection: 01DING~1 (5 sec) 2020-02-09 12:17:45.078 Detail, Node029, Received: 0x01, 0x13, 0x00, 0x04, 0x00, 0x1d, 0x0d, 0x60, 0x0d, 0x03, 0x01, 0x71, 0x05, 0x00, 0x00, 0x00, 0xff, 0x0e, 0x01, 0x00, 0x13 2020-02-09 12:17:45.078 Detail, 2020-02-09 12:17:45.078 Info, Node029, Received a MultiChannelEncap from node 29, endpoint 3 for Command Class COMMAND_CLASS_NOTIFICATION 2020-02-09 12:17:45.078 Info, Node029, Got a AlarmCmd_Report Message.... 2020-02-09 12:17:45.078 Info, Node029, Received Notification report (>v1): Type: Siren (14) Event: Active (1) Status: true, Param Length: 0 2020-02-09 12:17:45.078 Detail, Node029, Refreshed Value: old value=0, new value=1, type=list 2020-02-09 12:17:45.078 Detail, Node029, Changes to this value are not verified 2020-02-09 12:17:45.078 Detail, Node029, Notification: ValueChanged CC: COMMAND_CLASS_NOTIFICATION Instance: 3 Index: 14 2020-02-09 12:17:45.078 Info, Notification: Value Changed Home 0xdd12ee37 Node 29 Genre user Class ALARM Instance 3 Index 14 Type list IsSet True ValueAsString Active 2020-02-09 12:17:50.576 Detail, Node029, Received: 0x01, 0x0d, 0x00, 0x04, 0x00, 0x1d, 0x07, 0x60, 0x0d, 0x03, 0x01, 0x79, 0x0a, 0x00, 0xf0 2020-02-09 12:17:50.576 Detail, 2020-02-09 12:17:50.576 Info, Node029, Received a MultiChannelEncap from node 29, endpoint 3 for Command Class COMMAND_CLASS_SOUND_SWITCH 2020-02-09 12:17:50.577 Info, Node029, Received SoundSwitch Tone Play report: 0 2020-02-09 12:17:50.577 Detail, Node029, Refreshed Value: old value=1, new value=0, type=list 2020-02-09 12:17:50.577 Detail, Node029, Changes to this value are not verified 2020-02-09 12:17:50.577 Detail, Node029, Notification: ValueChanged CC: COMMAND_CLASS_SOUND_SWITCH Instance: 3 Index: 1 2020-02-09 12:17:50.577 Info, Notification: Value Changed Home 0xdd12ee37 Node 29 Genre user Class SOUND SWITCH Instance 3 Index 1 Type list IsSet True ValueAsString Inactive List Item: 0 Selection: Inactive 2020-02-09 12:17:50.677 Detail, Node029, Received: 0x01, 0x13, 0x00, 0x04, 0x00, 0x1d, 0x0d, 0x60, 0x0d, 0x03, 0x01, 0x71, 0x05, 0x00, 0x00, 0x00, 0xff, 0x0e, 0x00, 0x00, 0x12 2020-02-09 12:17:50.677 Detail, 2020-02-09 12:17:50.677 Info, Node029, Received a MultiChannelEncap from node 29, endpoint 3 for Command Class COMMAND_CLASS_NOTIFICATION 2020-02-09 12:17:50.677 Info, Node029, Got a AlarmCmd_Report Message.... 2020-02-09 12:17:50.677 Info, Node029, Received Notification report (>v1): Type: Siren (14) Event: Clear (0) Status: true, Param Length: 0 2020-02-09 12:17:50.677 Detail, Node029, Refreshed Value: old value=1, new value=0, type=list 2020-02-09 12:17:50.677 Detail, Node029, Changes to this value are not verified 2020-02-09 12:17:50.677 Detail, Node029, Notification: ValueChanged CC: COMMAND_CLASS_NOTIFICATION Instance: 3 Index: 14 2020-02-09 12:17:50.678 Info, Notification: Value Changed Home 0xdd12ee37 Node 29 Genre user Class ALARM Instance 3 Index 14 Type list IsSet True ValueAsString Clear

(thats pressing the doorbell button - So endpoint 3)

aretakisv commented 4 years ago

@Fishwaldo Which ever device I do include encrypted.. I have corruption and/or issues with the device

An example of my Eurotronic Spirit. secured doesn't work as should, where insecure do.

I am running on Raspberry PI (arm64 open-zwave) and also with the latest doorbell patches. The issue above also found on previous versions

The secure part, might be a more generic issue?

Two same devices next to each other:

15 secure, 17 insecure

Screenshot 2020-02-09 at 08 08 58

Also parameters are completely wrong. First reports Cooling and second normal heating

Refresh node info and errors:

zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09T07:10:45.558Z z2m:App Zwave api call: refreshNodeInfo [ 15 ]
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.559 Detail, Node015, Reloading Node
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.624 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.624 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_THERMOSTAT_MODE Instance: 1 Index: 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.625 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_ZWAVEPLUS_INFO Instance: 1 Index: 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.625 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_MANUFACTURER_SPECIFIC Instance: 1 Index: 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.625 Warning, Value Not Deleted - Still in use 1 times: CC: 49 - list - Air Temperature Units - 265043988
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.625 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_PROTECTION Instance: 1 Index: 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Info, Saving Cache
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_BATTERY Instance: 1 Index: 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_VERSION Instance: 1 Index: 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_SECURITY Instance: 1 Index: 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_SENSOR_MULTILEVEL Instance: 1 Index: 1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_ZWAVEPLUS_INFO Instance: 1 Index: 1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_MANUFACTURER_SPECIFIC Instance: 1 Index: 1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.626 Info, Node001, Cache Save for Node 1 as its QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.627 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.627 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_VERSION Instance: 1 Index: 1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.627 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 2
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.627 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_THERMOSTAT_SETPOINT Instance: 1 Index: 2
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.627 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_ZWAVEPLUS_INFO Instance: 1 Index: 2
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.627 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_MANUFACTURER_SPECIFIC Instance: 1 Index: 2
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.627 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 2
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.627 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_VERSION Instance: 1 Index: 2
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.627 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 3
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.628 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_MANUFACTURER_SPECIFIC Instance: 1 Index: 3
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.628 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 3
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.628 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_SWITCH_MULTILEVEL Instance: 1 Index: 4
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.628 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_MANUFACTURER_SPECIFIC Instance: 1 Index: 4
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.628 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 4
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.628 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 5
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.628 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 6
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.628 Info, Node002, Cache Save for Node 2 as its QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.629 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 7
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.629 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_THERMOSTAT_SETPOINT Instance: 1 Index: 8
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.629 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_NOTIFICATION Instance: 1 Index: 8
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.629 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 8
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.629 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_NOTIFICATION Instance: 1 Index: 9
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.629 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_POWERLEVEL Instance: 1 Index: 9
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.629 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_SENSOR_MULTILEVEL Instance: 1 Index: 256
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.629 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_NOTIFICATION Instance: 1 Index: 256
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.630 Detail, Node015, Notification: ValueRemoved CC: COMMAND_CLASS_NOTIFICATION Instance: 1 Index: 266
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.630 Info, Node004, Cache Save for Node 4 as its QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.632 Info, Node005, Cache Save for Node 5 as its QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.632 Info, Node007, Skipping Cache Save for Node 7 as its not past QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.637 Info, Node011, Cache Save for Node 11 as its QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.637 Info, Node013, Skipping Cache Save for Node 13 as its not past QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.638 Info, Node014, Cache Save for Node 14 as its QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.639 Info, Node016, Cache Save for Node 16 as its QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.642 Info, Node017, Cache Save for Node 17 as its QueryStage_CacheLoad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.684 Detail, Node015, Notification: NodeRemoved
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.684 Detail, Node015, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.684 Detail, Node015, QueryStage_ProtocolInfo
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.685 Detail, Node015, Notification: NodeAdded
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.685 Detail, Node015, Queuing (Query) Get Node Protocol Info (Node=15): 0x01, 0x04, 0x00, 0x41, 0x0f, 0xb5
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.685 Detail, Node015, Queuing (Query) Query Stage Complete (ProtocolInfo)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.685 Info, Node015, Initializing Node. New Node: false (false)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.692 Detail,
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.692 Info, Node015, Sending (Query) message (Callback ID=0x00, Expected Reply=0x41) - Get Node Protocol Info (Node=15): 0x01, 0x04, 0x00, 0x41, 0x0f, 0xb5
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.693 Info, Node015, Encrypted Flag is 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09T07:10:45.692Z z2m:Zwave Success zwave api call refreshNodeInfo
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Detail, Node015,   Received: 0x01, 0x09, 0x01, 0x41, 0x53, 0xdc, 0x01, 0x04, 0x08, 0x06, 0x32
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Detail,
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015, Received reply to FUNC_ID_ZW_GET_NODE_PROTOCOL_INFO
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,   Protocol Info for Node 15:
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,     Listening     = false
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,     Frequent      = true
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,     Beaming       = true
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,     Routing       = true
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,     Max Baud Rate = 100000
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,     Version       = 4
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,     Security      = false
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,   Basic device class    (0x04) - Routing Slave
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.695 Info, Node015,   Generic device Class  (0x08) - Thermostat
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,   Specific device class (0x06) - General Thermostat V2
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,     COMMAND_CLASS_BASIC will be mapped to COMMAND_CLASS_THERMOSTAT_MODE
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,   Mandatory Command Classes for Node 15:
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,     COMMAND_CLASS_BASIC
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,     COMMAND_CLASS_THERMOSTAT_MODE
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,     COMMAND_CLASS_THERMOSTAT_SETPOINT
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,     COMMAND_CLASS_MANUFACTURER_SPECIFIC
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,     COMMAND_CLASS_VERSION
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,   Mandatory Command Classes controlled by Node 15:
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015,     None
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Detail, Node015,   Expected reply was received
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Detail, Node015,   Message transaction complete
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09T07:10:45.696Z z2m:Zwave Node removed 15
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Detail,
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Detail, Node015, Removing current message
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Detail, Node015, Notification: NodeProtocolInfo
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Detail, Node015, Query Stage Complete (ProtocolInfo)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Detail, Node015, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Detail, Node015, QueryStage_Probe
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.696 Info, Node015, NoOperation::Set - Routing=true
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.697 Detail, Node015, Queuing (NoOp) NoOperation_Set (Node=15): 0x01, 0x09, 0x00, 0x13, 0x0f, 0x02, 0x00, 0x00, 0x25, 0xb6, 0x7b
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.697 Detail, Node015, Queuing (Query) Query Stage Complete (Probe)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.697 Detail,
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.697 Info, Node015, Sending (NoOp) message (Callback ID=0xb6, Expected Reply=0x13) - NoOperation_Set (Node=15): 0x01, 0x09, 0x00, 0x13, 0x0f, 0x02, 0x00, 0x00, 0x25, 0xb6, 0x7b
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.697 Info, Node015, Encrypted Flag is 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09T07:10:45.697Z z2m:Zwave Node added 15
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.704 Detail, Node015,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:45.705 Detail, Node015,   ZW_SEND_DATA delivered to Z-Wave stack
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.111 Detail, Node015,   Received: 0x01, 0x07, 0x00, 0x13, 0xb6, 0x00, 0x00, 0x8d, 0xd0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.111 Detail, Node015,   ZW_SEND_DATA Request with callback ID 0xb6 received (expected 0xb6)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.111 Info, Node015, Request RTT 1415 Average Request RTT 1415
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.111 Detail, Node015,   Expected callbackId was received
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.111 Detail, Node015,   Expected reply was received
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.112 Detail, Node015,   Message transaction complete
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.112 Detail,
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.112 Detail, Node015, Removing current message
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.112 Detail, Node015, Notification: Notification - NoOperation
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.112 Detail, Node015, Query Stage Complete (Probe)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.112 Detail, Node015, AdvanceQueries queryPending=0 queryRetries=0 queryStage=WakeUp live=1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.112 Detail, Node015, QueryStage_WakeUp
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.113 Detail, Node015, QueryStage_Versions
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.113 Info, Node015, Requesting Versions
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.113 Info, Node015, Requesting Versions for COMMAND_CLASS_NO_OPERATION - Max: 1 - Current 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.113 Info, Node015, Requesting Versions for COMMAND_CLASS_BASIC - Max: 1 - Current 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.113 Info, Node015, Requesting Versions for COMMAND_CLASS_THERMOSTAT_MODE - Max: 3 - Current 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.113 Detail, Node015, Queuing (Send) VersionCmd_CommandClassGet (Node=15): 0x01, 0x0a, 0x00, 0x13, 0x0f, 0x03, 0x86, 0x13, 0x40, 0x25, 0xb7, 0xad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09T07:10:47.113Z z2m:Zwave Notification from node 15: Notification - NoOperation (2)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.113 Info, Node015, Requesting Versions for COMMAND_CLASS_THERMOSTAT_SETPOINT - Max: 3 - Current 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.114 Detail, Node015, Queuing (Send) VersionCmd_CommandClassGet (Node=15): 0x01, 0x0a, 0x00, 0x13, 0x0f, 0x03, 0x86, 0x13, 0x43, 0x25, 0xb8, 0xa1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.114 Info, Node015, Requesting Versions for COMMAND_CLASS_MANUFACTURER_SPECIFIC - Max: 2 - Current 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.114 Detail, Node015, Queuing (Send) VersionCmd_CommandClassGet (Node=15): 0x01, 0x0a, 0x00, 0x13, 0x0f, 0x03, 0x86, 0x13, 0x72, 0x25, 0xb9, 0x91
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.114 Info, Node015, Requesting Versions for COMMAND_CLASS_VERSION - Max: 1 - Current 1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.115 Detail, Node015, Queuing (Query) Query Stage Complete (Versions)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.115 Detail,
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.115 Info, Node015, Sending (Send) message (Callback ID=0xb7, Expected Reply=0x04) - VersionCmd_CommandClassGet (Node=15): 0x01, 0x0a, 0x00, 0x13, 0x0f, 0x03, 0x86, 0x13, 0x40, 0x25, 0xb7, 0xad
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.115 Info, Node015, Encrypted Flag is 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.123 Detail, Node015,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.123 Detail, Node015,   ZW_SEND_DATA delivered to Z-Wave stack
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.195 Detail, Node015,   Received: 0x01, 0x07, 0x00, 0x13, 0xb7, 0x00, 0x00, 0x07, 0x5b
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.196 Detail, Node015,   ZW_SEND_DATA Request with callback ID 0xb7 received (expected 0xb7)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.196 Info, Node015, Request RTT 80 Average Request RTT 747
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:47.196 Detail, Node015,   Expected callbackId was received
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:57.116 Error, Node015, ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "VersionCmd_CommandClassGet (Node=15): 0x01, 0x0a, 0x00, 0x13, 0x0f, 0x03, 0x86, 0x13, 0x40, 0x25, 0xb7, 0xad"
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:57.117 Detail, Node015, Removing current message
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:57.117 Detail, Node015, Notification: Notification - TimeOut
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:57.117 Detail,
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:57.117 Info, Node015, Sending (Send) message (Callback ID=0xb8, Expected Reply=0x04) - VersionCmd_CommandClassGet (Node=15): 0x01, 0x0a, 0x00, 0x13, 0x0f, 0x03, 0x86, 0x13, 0x43, 0x25, 0xb8, 0xa1
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:57.117 Info, Node015, Encrypted Flag is 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09T07:10:57.117Z z2m:Zwave Notification from node 15: Notification - TimeOut (1)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:57.124 Detail, Node015,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:57.125 Detail, Node015,   ZW_SEND_DATA delivered to Z-Wave stack
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:58.502 Detail, Node015,   Received: 0x01, 0x07, 0x00, 0x13, 0xb8, 0x00, 0x00, 0x8a, 0xd9
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:58.503 Detail, Node015,   ZW_SEND_DATA Request with callback ID 0xb8 received (expected 0xb8)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:58.503 Info, Node015, Request RTT 1385 Average Request RTT 1066
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:10:58.503 Detail, Node015,   Expected callbackId was received
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:07.118 Detail, Node015, Removing current message
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:07.118 Detail, Node015, Notification: Notification - TimeOut
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:07.118 Detail,
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:07.118 Info, Node015, Sending (Send) message (Callback ID=0xb9, Expected Reply=0x04) - VersionCmd_CommandClassGet (Node=15): 0x01, 0x0a, 0x00, 0x13, 0x0f, 0x03, 0x86, 0x13, 0x72, 0x25, 0xb9, 0x91
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:07.118 Info, Node015, Encrypted Flag is 0
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09T07:11:07.119Z z2m:Zwave Notification from node 15: Notification - TimeOut (1)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:07.127 Detail, Node015,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:07.127 Detail, Node015,   ZW_SEND_DATA delivered to Z-Wave stack
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:08.538 Detail, Node015,   Received: 0x01, 0x07, 0x00, 0x13, 0xb9, 0x00, 0x00, 0x8e, 0xdc
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:08.539 Detail, Node015,   ZW_SEND_DATA Request with callback ID 0xb9 received (expected 0xb9)
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:08.539 Info, Node015, Request RTT 1419 Average Request RTT 1242
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:08.539 Detail, Node015,   Expected callbackId was received
zwave2mqtt2-f9b8555c7-m974x zwave2mqtt 2020-02-09 07:11:17.119 Error, Node015, ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "VersionCmd_CommandClassGet (Node=15): 0x01, 0x0a, 0x00, 0x13, 0x0f, 0x03, 0x86, 0x13, 0x72, 0x25, 0xb9, 0x91"
Fishwaldo commented 4 years ago

I need to see the full OZW log file after removing ozwcache or attempting to include a new device securely.

varet80 commented 4 years ago

Latest master commit fixes the issue. Still pending the DB6 issue with the encryption.

varet80 commented 4 years ago

@Fishwaldo Doorbell 6 still has corruption under encrypted inclusion... after almost 3 failed inclusions (secure) the last is still give corruption. I am re-including it insecure, in order to be able to handle it.

Pastebin of log: https://pastebin.com/zrsnJr2W

LordMike commented 4 years ago

Just chiming in to say I'm seeing this corruption issue too - and anecdotally, the issue with setting an inactive sound. It seems I have FW 1.04, running Zwave2Mqtt w/ OZW 1.6.1061.

I've received logs like this image

And configuration options like this: image

Definitely wasn't corrupted in insecure.

Fishwaldo commented 4 years ago

I can't reproduce it - So maybe Locale issue. Are any of you running your linux with something other that UTF8 or C locales?

LordMike commented 4 years ago

Mine's run in docker. I can't see an LC_* environment variable set in the container.. how can I check it?

aee0 commented 4 years ago

Any news on this one? I am experiencing this issue both in a Docker container and also in a Debian 9 VM (both for zwave2mqtt). On the Debian install, my locale is en_CA.UTF-8:

$ locale
LANG=en_CA.UTF-8
LANGUAGE=en_CA:en
LC_CTYPE="en_CA.UTF-8"
LC_NUMERIC="en_CA.UTF-8"
LC_TIME="en_CA.UTF-8"
LC_COLLATE="en_CA.UTF-8"
LC_MONETARY="en_CA.UTF-8"
LC_MESSAGES="en_CA.UTF-8"
LC_PAPER="en_CA.UTF-8"
LC_NAME="en_CA.UTF-8"
LC_ADDRESS="en_CA.UTF-8"
LC_TELEPHONE="en_CA.UTF-8"
LC_MEASUREMENT="en_CA.UTF-8"
LC_IDENTIFICATION="en_CA.UTF-8"
LC_ALL=
LordMike commented 4 years ago

No news. I have not since attempted an S2 setup.

Aohzan commented 3 years ago

Hello,

I have the same problem, with a secure inclusion or not, event after a hard reset. When I try to update the volume: ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "MultiChannel Encapsulated (instance=1): SoundSwitchCmd_Tones_Config_Set

I use zwave2mqtt addon on home assistant.

Did someone find a solution ?

varet80 commented 3 years ago

I moved to zwavejs2mqtt. Works as expected

Gilles-Clemenceau commented 3 years ago

Hello,

I have the same problem, with a secure inclusion or not, event after a hard reset. When I try to update the volume: ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "MultiChannel Encapsulated (instance=1): SoundSwitchCmd_Tones_Config_Set

I use zwave2mqtt addon on home assistant.

Did someone find a solution ?

Same for me with zwave2mqtt addon on home assistant.

Crinisen commented 3 years ago

I am having this issue as well. I have not spent any time with Node.js so can't really dive in myself but am willing to provide whatever information needed to try and track the issue down if you just let me know what command I would need to run where and / or what logs to grab. Using zwave2mqtt + eclicse-mosquitto:1.6-openssl docker image

woddou commented 3 years ago

Same here and it looks to be the same issue I raised today. Buggy decryption of dataload with lenght higher than 20 char in Secure mode (my guess following code investigation) leads to corrupted OZW XML files and stop OZW panel load (due to corrupted XML). Other than that Domoticz and OZW work like a charme despite this issue but as you guys I'm not able to configure DB6 and basically to use it correctly.