Pi3 + RaZberry2

[riro]

Really close to giving up now...

I have tested to compile from source, use the script from install.domoticz.com on multiple different Jessie-images.
Right now I'm running 4.4.43-v7 as Kent in this thread viewtopic.php?f=24&t=13775 got to work.

I can get the RaZbian to work with z-wave.me's web interface and I can control the switches I have been testing with... so it's not a hardware issue.

This is the log, I did delete the domoticz.db and after the web is started I add the RaZbian controller on /dev/ttyAMA0 then I get a timeout.

Code: Select all

pi@domoticz:~/dev-domoticz $ sudo ./domoticz -www 80 -sslwww 0 -log /var/log/domoticz.log -loglevel 0
2017-01-20 07:40:28.754  Domoticz V3.6389 (c)2012-2017 GizMoCuz
2017-01-20 07:40:28.755  Build Hash: 62434a5, Date: 2017-01-19 14:01:46
2017-01-20 07:40:28.755  System: Raspberry Pi
2017-01-20 07:40:28.755  Startup Path: /home/pi/dev-domoticz/
2017-01-20 07:40:29.244  PluginSystem: Started, Python version '3.4.2'.
2017-01-20 07:40:29.250  Active notification Subsystems: http (1/12)
2017-01-20 07:40:29.279  WebServer(HTTP) started on address: :: with port 80
2017-01-20 07:40:29.286  Proxymanager started.
2017-01-20 07:40:29.287  Starting shared server on: :::6144
2017-01-20 07:40:29.287  TCPServer: shared server started...
2017-01-20 07:40:29.288  RxQueue: queue worker started...
2017-01-20 07:40:31.289  EventSystem: reset all events...
2017-01-20 07:40:31.290  EventSystem: reset all device statuses...
2017-01-20 07:40:31.290  EventSystem: Started
2017-01-20 07:40:31.541  PluginSystem: Entering work loop.
2017-01-20 07:40:39.298  Incoming connection from: 192.168.100.116
2017-01-20 07:41:00.035  OpenZWave: Starting...
2017-01-20 07:41:00.035  OpenZWave: Version: 1.4-2360-ge33f955-dirty
2017-01-20 07:41:00.035  OpenZWave: using config in: /home/pi/dev-domoticz/Config/
2017-01-20 07:41:00.047 Always, OpenZwave Version 1.4.2360 Starting Up
2017-01-20 07:41:11.079 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-01-20 07:41:11.079 Always,
2017-01-20 07:41:11.079 Always, Dumping queued log messages
2017-01-20 07:41:11.079 Always,
2017-01-20 07:41:11.079 Always,
2017-01-20 07:41:11.079 Always, End of queued log message dump
2017-01-20 07:41:11.079 Always,
2017-01-20 07:41:11.080  OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 255 (0xff)
2017-01-20 07:41:12.145 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-01-20 07:41:12.145 Always,
2017-01-20 07:41:12.145 Always, Dumping queued log messages
2017-01-20 07:41:12.145 Always,
2017-01-20 07:41:12.145 Always,
2017-01-20 07:41:12.145 Always, End of queued log message dump
2017-01-20 07:41:12.145 Always,
2017-01-20 07:41:12.146  OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 255 (0xff)
2017-01-20 07:41:13.642 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-01-20 07:41:13.642 Always,
2017-01-20 07:41:13.642 Always, Dumping queued log messages
2017-01-20 07:41:13.642 Always,
2017-01-20 07:41:13.643 Always,
2017-01-20 07:41:13.643 Always, End of queued log message dump
2017-01-20 07:41:13.643 Always,
2017-01-20 07:41:13.643  OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 255 (0xff)
2017-01-20 07:41:15.142 Error, contrlr, ERROR: Dropping command, expected response not received after 1 attempt(s)
2017-01-20 07:41:15.142 Always,
2017-01-20 07:41:15.143 Always, Dumping queued log messages
2017-01-20 07:41:15.143 Always,
2017-01-20 07:41:15.143 Always,
2017-01-20 07:41:15.143 Always, End of queued log message dump
2017-01-20 07:41:15.143 Always,
2017-01-20 07:41:15.143  OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 255 (0xff)

2017-01-20 07:41:58.837  Closing application!...
2017-01-20 07:41:58.837  Stopping worker...
2017-01-20 07:41:58.837  RxQueue: queue worker stopped...
2017-01-20 07:41:58.838  WebServer(HTTP) stopped
2017-01-20 07:41:59.339  TCPServer: shared server stopped
2017-01-20 07:41:59.339  Stopping all hardware...
2017-01-20 07:41:59.566  OpenZWave: Closed
2017-01-20 07:42:00.566 Always, ***************************************************************************
2017-01-20 07:42:00.567 Always, *********************  Cumulative Network Statistics  *********************
2017-01-20 07:42:00.567 Always, *** General
2017-01-20 07:42:00.567 Always, Driver run time: . .  . 0 days, 0 hours, 1 minutes
2017-01-20 07:42:00.567 Always, Frames processed: . . . . . . . . . . . . . . . . . . . . 12
2017-01-20 07:42:00.567 Always, Total messages successfully received: . . . . . . . . . . 6
2017-01-20 07:42:00.567 Always, Total Messages successfully sent: . . . . . . . . . . . . 10
2017-01-20 07:42:00.567 Always, ACKs received from controller:  . . . . . . . . . . . . . 5
2017-01-20 07:42:00.567 Always, *** Errors
2017-01-20 07:42:00.567 Always, Unsolicited messages received while waiting for ACK:  . . 5
2017-01-20 07:42:00.567 Always, Reads aborted due to timeouts:  . . . . . . . . . . . . . 4
2017-01-20 07:42:00.568 Always, Bad checksum errors:  . . . . . . . . . . . . . . . . . . 2
2017-01-20 07:42:00.568 Always, CANs received from controller:  . . . . . . . . . . . . . 1
2017-01-20 07:42:00.568 Always, NAKs received from controller:  . . . . . . . . . . . . . 0
2017-01-20 07:42:00.568 Always, Out of frame data flow errors:  . . . . . . . . . . . . . 23
2017-01-20 07:42:00.568 Always, Messages retransmitted: . . . . . . . . . . . . . . . . . 1
2017-01-20 07:42:00.568 Always, Messages dropped and not delivered: . . . . . . . . . . . 4
2017-01-20 07:42:00.568 Always, ***************************************************************************
2017-01-20 07:42:03.300  Scheduler stopped...
2017-01-20 07:42:03.579  EventSystem: Stopped...
2017-01-20 07:42:03.585  PluginSystem: Exiting work loop.
2017-01-20 07:42:03.607  PluginSystem: Stopped.
2017-01-20 07:42:04.078  Mainworker Stopped...
pi@domoticz:~/dev-domoticz $

I had the Controller running and one switch in the network... but still got the timeout's. Wrote a shorter thread on a Swedish board... but no answer there.
This is the log I got then:

Code: Select all

2017-01-16 22:20:01.576  User: Admin initiated a switch command (1/Switch/Off)
2017-01-16 22:20:01.577  OpenZWave: Domoticz has send a Switch command! NodeID: 3 (0x03)
2017-01-16 22:20:01.578  (RaZ) Light/Switch (Switch)
2017-01-16 22:20:11.601  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:21:23.040  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:22:33.049  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:23:43.058  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:24:53.067  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:26:03.077  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:27:13.086  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:28:23.095  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:31:38.034  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:32:48.043  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)
2017-01-16 22:33:58.052  OpenZWave: Received timeout notification from HomeID: 4175844085, NodeID: 3 (0x03)

I have tried to reset the controller, same issue.
No "strange" things installed, a really clean image with Domoticz compiled from source (git pull yesterday) with the instructions on the wiki.


Anyone with suggestions on how to move forward?

Possible to debug-log the z-wave engine?

[jonas]

Write image Raspian Jessie Lite on sd-card.

1. sudo raspi-config
1.1 expand Filesystem
1.2 Change User Password
1.3 Change Timezone: set to appropriate timezone
1.4 Advanced Options: Option A3. Memory Split: Set to 16M since we don't need a graphical environment
1.5 Advanced Options: Option A0. Update
1.6 sudo reboot
2. sudo apt-get update
3. sudo apt-get upgrade
4. sudo reboot
5. sudo apt-get install rpi-update
6. sudo rpi-update
7. sudo nano /boot/config.txt
add to end of the file:
dtoverlay=pi3-disable-bt
8. removing 'console=serial0,115200' from cmdline.txt
sudo nano /boot/cmdline.txt
9. sudo systemctl disable hciuart
10. sudo stty -F /dev/ttyAMA0 9600
11. sudo reboot
12. sudo curl -L install.domoticz.com | bash

Yes I'm using RaZberry2, type OpenZWave USB with /dev/ttyAMA0 in Domoticz!

Good luck!

[riro]

Changed to static IP and WiFi, other than that I did exactly (!) as the guide.

Used 2017-01-11-raspbian-jessie-lite.img and now "uname -a" returns

Code: Select all

Linux raspberrypi 4.4.43-v7+ #948 SMP Sun Jan 15 22:20:07 GMT 2017 armv7l GNU/Linux

I can see the controller, and the switch... but there is still a timeout when I toggle the switch.

Code: Select all

2017-01-20 14:20:30.612 Domoticz V3.5877 (c)2012-2016 GizMoCuz
2017-01-20 14:20:30.612 Build Hash: 15b013c, Date: 2016-11-10 16:44:33
2017-01-20 14:20:30.613 System: Raspberry Pi
2017-01-20 14:20:30.614 Startup Path: /home/pi/domoticz/
2017-01-20 14:20:30.842 Active notification subsystems: http (1/12)
2017-01-20 14:20:30.844 WebServer(HTTP) started on address: :: with port 80
2017-01-20 14:20:30.846 Proxymanager started.
2017-01-20 14:20:30.848 Starting shared server on: :::6144
2017-01-20 14:20:30.849 RxQueue: queue worker started...
2017-01-20 14:20:30.850 TCPServer: shared server started...
2017-01-20 14:20:32.850 EventSystem: reset all events...
2017-01-20 14:20:32.851 EventSystem: reset all device statuses...
2017-01-20 14:20:32.851 EventSystem: Started
2017-01-20 14:20:44.114 Incoming connection from: 192.168.100.116
2017-01-20 14:21:08.501 OpenZWave: Starting...
2017-01-20 14:21:08.502 OpenZWave: Version: 1.4-2228-gbce4a88-dirty
2017-01-20 14:21:08.502 OpenZWave: using config in: /home/pi/domoticz/Config/
2017-01-20 14:21:08.642 OpenZWave: Driver Ready
2017-01-20 14:21:08.642 OpenZWave: New Node added. HomeID: 4289212457, NodeID: 1 (0x01)
2017-01-20 14:21:08.643 OpenZWave: New Node added. HomeID: 4289212457, NodeID: 1 (0x01)
2017-01-20 14:21:08.643 OpenZWave: New Node added. HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:21:08.644 OpenZWave: New Node added. HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:21:08.718 OpenZWave: Value_Added: Node: 2 (0x02), CommandClass: SWITCH BINARY, Label: Switch, Instance: 1
2017-01-20 14:21:08.718 (RaZberry Controller) Light/Switch (Switch)
2017-01-20 14:21:18.870 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:21:28.912 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:21:38.946 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:21:48.955 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:21:58.957 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:22:08.959 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:22:08.960 OpenZWave: All Nodes queried
2017-01-20 14:22:51.535 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:24:01.537 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:24:11.561 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:24:21.584 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:24:31.608 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:25:41.611 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:26:00.850 User: Admin initiated a switch command (1/Switch/On)
2017-01-20 14:26:00.851 OpenZWave: Domoticz has send a Switch command! NodeID: 2 (0x02)
2017-01-20 14:26:00.851 (RaZberry Controller) Light/Switch (Switch)
2017-01-20 14:26:10.874 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)
2017-01-20 14:26:21.235 User: Admin initiated a switch command (1/Switch/Off)
2017-01-20 14:26:21.235 OpenZWave: Domoticz has send a Switch command! NodeID: 2 (0x02)
2017-01-20 14:26:21.236 (RaZberry Controller) Light/Switch (Switch)
2017-01-20 14:26:31.259 OpenZWave: Received timeout notification from HomeID: 4289212457, NodeID: 2 (0x02)

[riro]

Hepp!

Was a bit to fast to answer...

I excluded the node, and included it again... and now it actually looks like it is working!

Thank you!