Just wanted to give some feedback on the equipment I do use:
- RaspberryPi 4 4GB
- External 120GB USB SSD
- Aeotec Z-Stick Gen5+
- Single Relay Module model TZ78 Plus
In my initial post I said that I did test with other software, however since it was only done as an limited test the installation of both openHAB and Domoticz was done to an SD card, i.e. in this case I did not use the external SSD disk. However in both cases when testing the other applications I could control the Z switch and issue commands to it just minutes after the software was up and running.
Also since I never managed to get the Z-wave protocol working in HA the pairing of the Z stick and the Z relay was done using the Zensys Tool, when trying to pair in HA it never succeeded. Also note initially I did have 3 external Z-wave devices which also where just next to the Rpi, two of them was battery powered. All devices where “visible” in HA, i.e. I could see them in the 1.4 Z-wave config file but for some reason only the first one (i.e. with lowest ID) was visible - and by visible I mean that you could see it as an device but it would state it as “unknown” and no mean to control it or get info from it.
Two of the devices used where battery powered temperature sensors but the battery would drain within one day…
Just found the “Advanced” option so I do have some logs from Open Z-wave now:
[20210205 10:09:11.542 CET] [ozwadmin] [info]: OZWCore Initilized QTOpenZwave(0x7f90091c20) QTOZWManager(0x555e7fca40)
[20210205 10:09:11.619 CET] [default] [warning]: QVncServer created on port 5900
[20210205 10:09:12.300 CET] [ozw.library] [info]: Info - Node: 0 Queuing download for http://download.db.openzwave.com/images/shenzen_neo/nas-sc03ze.jpg (Node 0)
[20210205 10:09:12.300 CET] [ozw.library] [info]: Info - Node: 0 Missing Picture /data/ozw/config/images/shenzen_neo/nas-sc03ze.jpg - Starting Download
[20210205 10:09:12.301 CET] [ozw.library] [debug]: Debug - Node: 0 Download Starting for http://download.db.openzwave.com/images/shenzen_neo/nas-sc03ze.jpg (/data/ozw/config/images/shenzen_neo/nas-sc03ze.jpg)
[20210205 10:09:12.523 CET] [ozw.library] [info]: Info - Node: 0 Queuing download for http://download.db.openzwave.com/images/namron/4512714.jpg (Node 0)
[20210205 10:09:12.523 CET] [ozw.library] [info]: Info - Node: 0 Missing Picture /data/ozw/config/images/namron/4512714.jpg - Starting Download
[20210205 10:09:12.558 CET] [ozw.library] [debug]: Debug - Node: 0 Downloads Remaining: 2
[20210205 10:09:12.692 CET] [ozw.library] [debug]: Debug - Node: 0 Download Starting for http://download.db.openzwave.com/images/namron/4512714.jpg (/data/ozw/config/images/namron/4512714.jpg)
[20210205 10:09:12.692 CET] [ozw.library] [warning]: Warning - Node: 0 Download of http://download.db.openzwave.com/images/shenzen_neo/nas-sc03ze.jpg Failed (Node: 0)
[20210205 10:09:12.692 CET] [ozw.library] [debug]: Debug - Node: 0 Downloads Remaining: 1
[20210205 10:09:12.693 CET] [ozw.library] [debug]: Detail - Node: 0 Notification: A Config File Failed to download
[20210205 10:09:12.693 CET] [ozw.notifications] [debug]: Notification pvt_ozwUserAlert NotificationTypes::Notification_User_ConfigFileDownloadFailed Thread: 0x7f9c50ce38
[20210205 10:09:12.693 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwUserAlert
[20210205 10:09:12.836 CET] [ozw.library] [warning]: Warning - Node: 0 Download of http://download.db.openzwave.com/images/namron/4512714.jpg Failed (Node: 0)
[20210205 10:09:12.836 CET] [ozw.library] [debug]: Debug - Node: 0 Downloads Remaining: 0
[20210205 10:09:12.836 CET] [ozw.library] [info]: Info - Node: 0 ManufacturerSpecificDB Initialized
[20210205 10:09:12.837 CET] [ozw.library] [debug]: Detail - Node: 0 Notification: A Config File Failed to download
[20210205 10:09:12.837 CET] [ozw.library] [debug]: Detail - Node: 0 Notification: ManufacturerSpecificDB Ready
[20210205 10:09:12.837 CET] [ozw.library] [info]: Info - Node: 255 Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
[20210205 10:09:12.837 CET] [ozw.library] [info]: Info - Node: 255 Encrypted Flag is 0
[20210205 10:09:12.837 CET] [ozw.notifications] [debug]: Notification pvt_ozwUserAlert NotificationTypes::Notification_User_ConfigFileDownloadFailed Thread: 0x7f9c50ce38
[20210205 10:09:12.838 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwUserAlert
[20210205 10:09:12.838 CET] [ozw.notifications] [debug]: Notification pvt_manufacturerSpecificDBReady Thread: 0x7f9c50ce38
[20210205 10:09:12.838 CET] [ozw.mqtt.publisher] [debug]: Publishing Event manufacturerSpecificDBReady
[20210205 10:09:12.839 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x37). Sending NAK.
[20210205 10:09:12.840 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x10). Sending NAK.
[20210205 10:09:12.841 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x2d). Sending NAK.
[20210205 10:09:12.841 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x97). Sending NAK.
[20210205 10:09:12.842 CET] [ozw.library] [debug]: Detail - Node: 0 Unsolicited message received while waiting for ACK.
[20210205 10:09:12.844 CET] [ozw.library] [debug]: Detail - Node: 255 Received: 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x36, 0x2e, 0x30, 0x37, 0x00, 0x01, 0x97, 0x01, 0x10, 0x57, 0x61, 0x65, 0x36, 0x30
[20210205 10:09:12.844 CET] [ozw.library] [warning]: Warning - Node: 255 WARNING: Checksum incorrect - sending NAK
WebSocket server settings:
- Listen on :8099
- Web server. Web root: /usr/share/novnc
- No SSL/TLS support (no cert file)
- proxying from :8099 to 127.0.0.1:5900
[20210205 10:09:13.844 CET] [ozw.library] [critical]: Error - Node: 255 ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9"
[20210205 10:09:13.845 CET] [ozw.library] [debug]: Detail - Node: 255 Removing current message
[20210205 10:09:13.845 CET] [ozw.library] [debug]: Detail - Node: 255 Notification: Notification - TimeOut
[20210205 10:09:13.845 CET] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_MsgTimeout Thread: 0x7f9c50ce38
[20210205 10:09:13.846 CET] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification
[20210205 10:09:14.165 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x00). Sending NAK.
[20210205 10:09:14.165 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x7f). Sending NAK.
[20210205 10:09:14.166 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x00). Sending NAK.
[20210205 10:09:14.167 CET] [ozw.library] [debug]: Detail - Node: 0 CAN received...triggering resend
[20210205 10:09:14.168 CET] [ozw.library] [warning]: Warning - Node: 0 m_currentMsg was NULL when trying to set MaxSendAttempts
[20210205 10:09:14.169 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x0b). Sending NAK.
[20210205 10:09:14.171 CET] [ozw.library] [debug]: Detail - Node: 0 CAN received...triggering resend
[20210205 10:09:14.172 CET] [ozw.library] [warning]: Warning - Node: 0 m_currentMsg was NULL when trying to set MaxSendAttempts
[20210205 10:09:14.173 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x13). Sending NAK.
[20210205 10:09:18.149 CET] [ozw.library] [debug]: Detail - Node: 0 Received: 0x01, 0x20, 0x01, 0x01, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5f, 0x01, 0x20, 0x80, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00
[20210205 10:09:18.151 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Checksum incorrect - sending NAK
[20210205 10:09:18.152 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x00). Sending NAK.
[20210205 10:09:18.153 CET] [ozw.library] [warning]: Warning - Node: 0 WARNING: Out of frame flow! (0x00). Sending NAK.
[20210205 10:09:22.836 CET] [ozw.library] [info]: Info - Node: 0 HttpThread Exiting. No Transfers in timeout period
1612516164: New connection from 172.30.32.1 on port 1883.
1612516164: New client connected from 172.30.32.1 as 3PyIK2Oev3E3HFktVzDDMj (p2, c1, k60, u'homeassistant').
[20210205 10:10:10.796 CET] [ozw.mqtt.publisher] [debug]: "Fri Feb 5 10:10:10 2021 PingResponse\n"
I can also add some info from Z-Wave for MTTQ which I installed in an attempt to solve the issue. In this log Node#1 is the Z-stick and #9 is the Z-wave switch.
2021-02-05T09:09:02.421Z z2m:Zwave Scanning network with homeid: 0xdd9b4469
2021-02-05T09:09:03.225Z z2m:Mqtt MQTT client connected
2021-02-05 10:09:08.124 Detail, Node009, Received: 0x01, 0x18, 0x00, 0x13, 0x0a, 0x01, 0x02, 0x5b, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x03, 0x00, 0x00, 0xdf
2021-02-05 10:09:08.124 Detail, Node009, ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a)
2021-02-05 10:09:08.124 Detail, Node009, Extended TxStatus: Time: 6030, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 3, FailedLinkFrom: 0, FailedLinkTo: 0
2021-02-05 10:09:08.124 Info, Node009, WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
2021-02-05 10:09:08.124 Warning, Node009, WARNING: Device is not a sleeping node.
2021-02-05 10:09:08.124 Info, Node009, QueryStageRetry stage CacheLoad requested stage CacheLoad max 3 retries 0 pending 1
2021-02-05 10:09:08.125 Detail, Node009, Expected callbackId was received
2021-02-05 10:09:08.125 Detail, Node009, Expected reply was received
2021-02-05 10:09:08.125 Detail, Node009, Message transaction complete
2021-02-05 10:09:08.125 Detail,
2021-02-05 10:09:08.125 Detail, Node009, Removing current message
2021-02-05 10:09:08.125 Detail, Node009, Notification: Notification - NoOperation
2021-02-05 10:09:08.125 Detail, Node001, Query Stage Complete (Neighbors)
2021-02-05 10:09:08.125 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2021-02-05 10:09:08.126 Detail, Node001, QueryStage_Session
2021-02-05 10:09:08.126 Detail, Node001, QueryStage_Dynamic
2021-02-05 10:09:08.126 Detail, Node001, QueryStage_Configuration
2021-02-05 10:09:08.126 Detail, Node001, QueryStage_Complete
2021-02-05 10:09:08.126 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2021-02-05 10:09:08.126 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=0
2021-02-05 10:09:08.126 Info, Saving Cache
2021-02-05 10:09:08.126 Info, Node001, Cache Save for Node 1 as its QueryStage_CacheLoad
2021-02-05 10:09:08.127 Info, Node009, Cache Save for Node 9 as its QueryStage_CacheLoad
2021-02-05 10:09:08.129 Detail, Node001, Notification: NodeQueriesComplete
2021-02-05 10:09:08.129 Detail, Node009, Query Stage Complete (CacheLoad)
2021-02-05T09:09:08.128Z z2m:Zwave Notification from node 9: Notification - NoOperation (2)
2021-02-05 10:09:08.129 Detail, Node009, AdvanceQueries queryPending=0 queryRetries=1 queryStage=CacheLoad live=1
2021-02-05 10:09:08.129 Detail, Node009, QueryStage_CacheLoad
2021-02-05 10:09:08.129 Info, Node009, Loading Cache for node 9: Manufacturer=Unknown: id=0000, Product=Unknown: type=0000, id=0000
2021-02-05 10:09:08.129 Info, Node009, Node Identity Codes: 0000:0000:0000
2021-02-05 10:09:08.129 Info, Node009, NoOperation::Set - Routing=true
2021-02-05 10:09:08.129 Detail, Node009, Queuing (NoOp) NoOperation_Set (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xc0
2021-02-05 10:09:08.129 Detail, Node009, Queuing (Query) Query Stage Complete (CacheLoad)
2021-02-05 10:09:08.129 Detail,
2021-02-05 10:09:08.130 Info, Node009, Sending (NoOp) message (Callback ID=0x0b, Expected Reply=0x13) - NoOperation_Set (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xc0
2021-02-05 10:09:08.130 Info, Node009, Encrypted Flag is 0
2021-02-05 10:09:08.156 Detail, Node009, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2021-02-05 10:09:08.157 Detail, Node009, ZW_SEND_DATA delivered to Z-Wave stack
2021-02-05T09:09:08.160Z z2m:Zwave node 1 ready: Unknown: id=0086 - Unknown: type=0001, id=005a (Unknown)
2021-02-05 10:09:12.841 Detail, Node009, Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x36, 0x2e, 0x30, 0x01, 0x65, 0x2e, 0x30
2021-02-05 10:09:12.841 Warning, Node009, WARNING: Checksum incorrect - sending NAK
2021-02-05 10:09:12.844 Warning, WARNING: Out of frame flow! (0x97). Sending NAK.
2021-02-05 10:09:14.168 Detail, Node009, Received: 0x01, 0x18, 0x13, 0x0b, 0x01, 0x02, 0x5b, 0x00, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x07, 0x00, 0x00, 0x02, 0x03, 0x00, 0x00, 0xde, 0x01, 0x00, 0x13, 0x00, 0x00
2021-02-05 10:09:14.169 Warning, Node009, WARNING: Checksum incorrect - sending NAK
2021-02-05 10:09:14.170 Detail, Node009, Received: 0x01, 0x00
2021-02-05 10:09:14.171 Warning, Node009, WARNING: Checksum incorrect - sending NAK
2021-02-05 10:09:14.172 Warning, WARNING: Out of frame flow! (0x02). Sending NAK.
2021-02-05 10:09:18.131 Error, Node009, ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "NoOperation_Set (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x00, 0x00, 0x25, 0x0b, 0xc0"
2021-02-05 10:09:18.131 Detail, Node009, Removing current message
2021-02-05 10:09:18.133 Detail, Node009, Notification: Notification - TimeOut
2021-02-05 10:09:18.134 Detail, Node009, Query Stage Complete (CacheLoad)
2021-02-05 10:09:18.135 Detail, Node009, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
2021-02-05 10:09:18.135 Detail, Node009, QueryStage_Associations
2021-02-05 10:09:18.136 Detail, Node009, QueryStage_Neighbors
2021-02-05T09:09:18.136Z z2m:Zwave Notification from node 9: Notification - TimeOut (1)
2021-02-05 10:09:18.136 Detail, Requesting routing info (neighbor list) for Node 9
2021-02-05 10:09:18.138 Detail, Node009, Queuing (Command) Get Routing Info (Node=9): 0x01, 0x07, 0x00, 0x80, 0x09, 0x00, 0x00, 0x03, 0x72
2021-02-05 10:09:18.139 Detail, Node009, Queuing (Query) Query Stage Complete (Neighbors)
2021-02-05 10:09:18.139 Detail,
2021-02-05 10:09:18.140 Info, Node009, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=9): 0x01, 0x07, 0x00, 0x80, 0x09, 0x00, 0x00, 0x03, 0x72
2021-02-05 10:09:18.141 Info, Node009, Encrypted Flag is 0
2021-02-05 10:09:18.144 Warning, WARNING: Out of frame flow! (0x80). Sending NAK.
2021-02-05 10:09:18.145 Warning, WARNING: Out of frame flow! (0x00). Sending NAK.
2021-02-05 10:09:18.147 Detail, Node009, Received: 0x01, 0x01, 0x00
2021-02-05 10:09:18.147 Warning, Node009, WARNING: Checksum incorrect - sending NAK
2021-02-05 10:09:18.148 Warning, WARNING: Out of frame flow! (0x00). Sending NAK.
2021-02-05 10:09:18.650 Warning, WARNING: 500ms passed without reading the rest of the frame...aborting frame read
2021-02-05 10:09:18.651 Detail, Node009, Received: 0x01, 0x00
2021-02-05 10:09:18.651 Warning, Node009, WARNING: Checksum incorrect - sending NAK
2021-02-05T09:09:26.871Z z2m:Mqtt Message received on homeassistant/status
2021-02-05T09:09:26.873Z z2m:Gateway Home Assistant is ONLINE
2021-02-05 10:09:28.142 Error, Node009, ERROR: Dropping command, expected response not received after 1 attempt(s). Command: "Get Routing Info (Node=9): 0x01, 0x07, 0x00, 0x80, 0x09, 0x00, 0x00, 0x03, 0x72"
2021-02-05 10:09:28.143 Detail, Node009, Removing current message
2021-02-05 10:09:28.144 Detail, Node009, Notification: Notification - TimeOut
2021-02-05 10:09:28.145 Detail, Node009, Query Stage Complete (Neighbors)
2021-02-05 10:09:28.146 Detail, Node009, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2021-02-05 10:09:28.146 Detail, Node009, QueryStage_Session
2021-02-05 10:09:28.147 Detail, Node009, QueryStage_Dynamic
2021-02-05 10:09:28.148 Detail, Node009, QueryStage_Configuration
2021-02-05 10:09:28.149 Detail, Node009, QueryStage_Complete
2021-02-05 10:09:28.150 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2021-02-05 10:09:28.150 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
2021-02-05 10:09:28.151 Info, Node query processing complete.
2021-02-05 10:09:28.151 Info, Saving Cache
2021-02-05 10:09:28.152 Info, Node001, Cache Save for Node 1 as its QueryStage_CacheLoad
2021-02-05 10:09:28.153 Info, Node009, Cache Save for Node 9 as its QueryStage_CacheLoad
2021-02-05 10:09:28.156 Detail, Node009, Notification: NodeQueriesComplete
2021-02-05T09:09:28.156Z z2m:Zwave Notification from node 9: Notification - TimeOut (1)
2021-02-05 10:09:28.157 Detail, contrlr, Notification: AllNodesQueried
2021-02-05T09:09:28.163Z z2m:Zwave node 9 ready: Unknown: id=0000 - Unknown: type=0000, id=0000 (Unknown)
2021-02-05T09:09:28.166Z z2m:Zwave Network scan complete. Found: 2 nodes