forum

Hi,

Issue, enabling the SmartHome software to work with the RazBerry2 module is not working. Following Error message persists:

An unexpected error occurred while initialising the Z-Wave controller data.
Please check
1.) if the controller is plugged in correctly,
2.) that in the app 'Z-Wave Network Access' the right port is entered
(UZB: '/dev/ttyACM0', Box: '/dev/ttyAMA0', UZB-Windows: '\\.\COM3', Z-Stick: '/dev/ttyUSB0', embedded boxes: '/dev/ttyS0' or '/dev/ttyS1')
3.) the app is aktiv. If not you could activate it under Menu > Apps > Activ or add a new one under Menu > Apps > Local.
The Setting 'Expert View' needs to be active under Menu > My Settings.

Setup:
Raspberry Pi 3 Model B with the default community build of Raspian (upgraded to latest)

PRETTY_NAME="Raspbian GNU/Linux 9 (stretch)"
NAME="Raspbian GNU/Linux"
VERSION_ID="9"
VERSION="9 (stretch)"
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

RazBerry2 module
SW installed with the command: wget -q -O - razberry.z-wave.me/install | sudo bash

Work done to resolve issues so far:
1# First issue was that the mylocalip:8083 didn't open at all, but this topic pointed out that there is some issue with the raspian version:
viewtopic.php?f=3422&t=25380&hilit=stretch
After running these lines, I was able to access the UI:
wget http://mirrordirector.raspbian.org/rasp ... _armhf.deb
sudo dpkg -i libssl1.0.0_1.0.1t-1+deb8u6_armhf.deb

2# I found this post:
viewtopic.php?t=25274

And of course following the instructions on the error message.

The bluetooth is off, as that is done by the installation script.

I've tried the different options in the error message, but still the error persists. Additionally when I try to enter e.g. /dev/ttyS1 I get additional error pop-up from UI "Something went wrong".

At the moment, I don't know what else to do. To me it seems that the homeautomation software is not able to find the RazBerry2 module at all.

Here's the latest log entries:

[2017-11-02 12:00:22.384] [D] [zway] Opened device: /dev/ttyAMA0
[2017-11-02 12:00:22.385] [D] [zway] Worker thread successfully created
[2017-11-02 12:00:22.385] [D] [zway] Worker thread entry point
[2017-11-02 12:00:22.385] [zway] Adding job: Get controller info and supported function classes
[2017-11-02 12:00:22.395] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-11-02 12:00:22.598] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-11-02 12:00:22.598] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-11-02 12:00:22.800] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-11-02 12:00:22.800] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-11-02 12:00:23.003] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-11-02 12:00:23.003] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2017-11-02 12:00:23.003] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2017-11-02 12:00:23.003] [C] [zway] Get Serial API Capabilities returned zero.
[2017-11-02 12:00:23.003] [zway] Removing job: Get controller info and supported function classes
[2017-11-02 12:00:23.013] [D] [zway] Worker thread exit point
[2017-11-02 12:00:23.013] [D] [zway] Worker thread successfully finished
[2017-11-02 12:00:23.014] [core] Restarting Z-Wave binding (zway)
[2017-11-02 12:00:23.015] [core] Error: Binding with name 'zway' already exists
[2017-11-02 12:00:23.015] [core] Trying to restart Z-Wave binding (zway) in 10 seconds
[2017-11-02 12:00:23.025] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2017-11-02 12:00:23.045] [core] Error: Bad data - communication failed
[2017-11-02 12:00:23.045] [core] Trying to restart Z-Wave binding (zway) in 10 seconds
[2017-11-02 12:00:33.027] [I] [core] Restarting Z-Wave binding (zway)
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data = Empty
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.nodeId = Empty
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.nodeId = 0 (0x00000000)
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.homeId = Empty
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.homeId = 0 (0x00000000)
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.SUCNodeId = Empty
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.SUCNodeId = 0 (0x00000000)
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.isPrimary = Empty
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.isPrimary = False
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.isInOthersNetwork = Empty
[2017-11-02 12:00:33.110] [D] [zway] SETDATA controller.data.isInOthersNetwork = False
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.isRealPrimary = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.isRealPrimary = False
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.isSUC = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.isSUC = False
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.SISPresent = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.SISPresent = False
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.libType = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.libType = ""
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.SDK = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.SDK = ""
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWlibMajor = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWlibMajor = 0 (0x00000000)
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWlibMinor = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWlibMinor = 0 (0x00000000)
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWLib = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWLib = 0 (0x00000000)
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWVersion = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWVersion = 0 (0x00000000)
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWaveChip = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.ZWaveChip = ""
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.APIVersion = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.APIVersion = ""
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.manufacturerId = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.manufacturerId = 0 (0x00000000)
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.vendor = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.vendor = ""
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.manufacturerProductType = Empty
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.manufacturerProductType = 0 (0x00000000)
[2017-11-02 12:00:33.111] [D] [zway] SETDATA controller.data.manufacturerProductId = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.manufacturerProductId = 0 (0x00000000)
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.bootloaderCRC = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.firmwareCRC = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.capabilities = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.controllerState = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.nonManagmentJobs = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.nonManagmentJobs = 0 (0x00000000)
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.lastIncludedDevice = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.lastIncludedDevice = 0 (0x00000000)
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.lastExcludedDevice = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.lastExcludedDevice = 0 (0x00000000)
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.secureInclusion = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.secureInclusion = True
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 0 (0x00000000)
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 0 (0x00000000)
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = 0 (0x00000000)
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = 0 (0x00000000)
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.countJobs = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.countJobs = False
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.memoryGetAddress = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.memoryGetData = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.memoryManufacturerId = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.memoryType = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.memoryCapacity = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.functionClasses = Empty
[2017-11-02 12:00:33.112] [D] [zway] SETDATA controller.data.functionClassesNames = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.softwareRevisionVersion = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.softwareRevisionVersion = "v2.3.6"
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.softwareRevisionId = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.softwareRevisionId = "a25d58db56fe93180d0e891dcc11d62d87ec8c2c"
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.softwareRevisionDate = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.softwareRevisionDate = "2017-10-04 15:13:33 +0300"
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.uuid = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.caps = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.capsNonce = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.countDown = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.frequency = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.frequency = ""
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.deviceRelaxDelay = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.deviceRelaxDelay = 3 (0x00000003)
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.incomingPacket = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.incomingPacket.nodeId = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.incomingPacket.dstNodeId = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.incomingPacket.securePayload = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.incomingPacket.RSSI = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.incomingPacket.frameType = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.outgoingPacket = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.outgoingPacket.delivered = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.outgoingPacket.deliveryTime = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.outgoingPacket.packetLength = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.outgoingPacket.nodeId = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.outgoingPacket.returnRSSI = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.outgoingPacket.hops = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.outgoingPacket.returnChannel = Empty
[2017-11-02 12:00:33.113] [D] [zway] SETDATA controller.data.outgoingPacket.txChannel = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.outgoingPacket.speed = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.outgoingPacket.schemeState = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.outgoingPacket.tries = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.outgoingPacket.lastFailPath = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.outgoingPacket.securePayload = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = 127 (0x0000007f)
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = 127 (0x0000007f)
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = 127 (0x0000007f)
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = 0 (0x00000000)
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = 0 (0x00000000)
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = 0 (0x00000000)
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = 0 (0x00000000)
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = 0 (0x00000000)
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = 0 (0x00000000)
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.priorityRoute = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.priorityRoute.dstNodeId = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.priorityRoute.routeType = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.priorityRoute.speed = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.statistics.priorityRoute.hops = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.homeName = Empty
[2017-11-02 12:00:33.114] [D] [zway] SETDATA controller.data.homeName = ""
[2017-11-02 12:00:33.115] [D] [zway] SETDATA controller.data.homeNotes = Empty
[2017-11-02 12:00:33.115] [D] [zway] SETDATA controller.data.homeNotes = ""
[2017-11-02 12:00:33.115] [D] [zway] SETDATA controller.data.functionClasses = int[65]
[2017-11-02 12:00:33.115] [D] [zway] [00] 2
[2017-11-02 12:00:33.115] [D] [zway] [01] 3
[2017-11-02 12:00:33.115] [D] [zway] [02] 4
[2017-11-02 12:00:33.115] [D] [zway] [03] 5
[2017-11-02 12:00:33.115] [D] [zway] [04] 6
[2017-11-02 12:00:33.115] [D] [zway] [05] 7
[2017-11-02 12:00:33.115] [D] [zway] [06] 8
[2017-11-02 12:00:33.115] [D] [zway] [07] 11
[2017-11-02 12:00:33.115] [D] [zway] [08] 18
[2017-11-02 12:00:33.115] [D] [zway] [09] 19
[2017-11-02 12:00:33.115] [D] [zway] [10] 21
[2017-11-02 12:00:33.115] [D] [zway] [11] 22
[2017-11-02 12:00:33.115] [D] [zway] [12] 23
[2017-11-02 12:00:33.115] [D] [zway] [13] 32
[2017-11-02 12:00:33.115] [D] [zway] [14] 33
[2017-11-02 12:00:33.115] [D] [zway] [15] 34
[2017-11-02 12:00:33.115] [D] [zway] [16] 35
[2017-11-02 12:00:33.115] [D] [zway] [17] 36
[2017-11-02 12:00:33.115] [D] [zway] [18] 39
[2017-11-02 12:00:33.115] [D] [zway] [19] 41
[2017-11-02 12:00:33.115] [D] [zway] [20] 42
[2017-11-02 12:00:33.115] [D] [zway] [21] 43
[2017-11-02 12:00:33.115] [D] [zway] [22] 44
[2017-11-02 12:00:33.115] [D] [zway] [23] 45
[2017-11-02 12:00:33.115] [D] [zway] [24] 57
[2017-11-02 12:00:33.116] [D] [zway] [25] 58
[2017-11-02 12:00:33.116] [D] [zway] [26] 59
[2017-11-02 12:00:33.116] [D] [zway] [27] 63
[2017-11-02 12:00:33.116] [D] [zway] [28] 65
[2017-11-02 12:00:33.116] [D] [zway] [29] 66
[2017-11-02 12:00:33.116] [D] [zway] [30] 68
[2017-11-02 12:00:33.116] [D] [zway] [31] 70
[2017-11-02 12:00:33.116] [D] [zway] [32] 71
[2017-11-02 12:00:33.116] [D] [zway] [33] 72
[2017-11-02 12:00:33.116] [D] [zway] [34] 73
[2017-11-02 12:00:33.116] [D] [zway] [35] 74
[2017-11-02 12:00:33.116] [D] [zway] [36] 75
[2017-11-02 12:00:33.116] [D] [zway] [37] 76
[2017-11-02 12:00:33.116] [D] [zway] [38] 77
[2017-11-02 12:00:33.116] [D] [zway] [39] 80
[2017-11-02 12:00:33.116] [D] [zway] [40] 81
[2017-11-02 12:00:33.116] [D] [zway] [41] 82
[2017-11-02 12:00:33.116] [D] [zway] [42] 83
[2017-11-02 12:00:33.116] [D] [zway] [43] 84
[2017-11-02 12:00:33.116] [D] [zway] [44] 85
[2017-11-02 12:00:33.116] [D] [zway] [45] 86
[2017-11-02 12:00:33.116] [D] [zway] [46] 87
[2017-11-02 12:00:33.116] [D] [zway] [47] 94
[2017-11-02 12:00:33.116] [D] [zway] [48] 96
[2017-11-02 12:00:33.116] [D] [zway] [49] 97
[2017-11-02 12:00:33.116] [D] [zway] [50] 98
[2017-11-02 12:00:33.116] [D] [zway] [51] 99
[2017-11-02 12:00:33.116] [D] [zway] [52] 128
[2017-11-02 12:00:33.116] [D] [zway] [53] 146
[2017-11-02 12:00:33.116] [D] [zway] [54] 147
[2017-11-02 12:00:33.116] [D] [zway] [55] 186
[2017-11-02 12:00:33.116] [D] [zway] [56] 190
[2017-11-02 12:00:33.116] [D] [zway] [57] 208
[2017-11-02 12:00:33.116] [D] [zway] [58] 209
[2017-11-02 12:00:33.116] [D] [zway] [59] 210
[2017-11-02 12:00:33.116] [D] [zway] [60] 211
[2017-11-02 12:00:33.116] [D] [zway] [61] 242
[2017-11-02 12:00:33.116] [D] [zway] [62] 243
[2017-11-02 12:00:33.116] [D] [zway] [63] 244
[2017-11-02 12:00:33.117] [D] [zway] [64] 245
[2017-11-02 12:00:33.117] [D] [zway] SETDATA controller.data.functionClassesNames = string[65]
[2017-11-02 12:00:33.117] [D] [zway] [00] "SerialAPIGetInitData"
[2017-11-02 12:00:33.117] [D] [zway] [01] "SerialAPIApplicationNodeInformation"
[2017-11-02 12:00:33.117] [D] [zway] [02] "ApplicationCommandHandler"
[2017-11-02 12:00:33.117] [D] [zway] [03] "GetControllerCapabilities"
[2017-11-02 12:00:33.117] [D] [zway] [04] "SerialAPISetTimeouts"
[2017-11-02 12:00:33.117] [D] [zway] [05] "GetSerialAPICapabilities"
[2017-11-02 12:00:33.117] [D] [zway] [06] "SerialAPISoftReset"
[2017-11-02 12:00:33.117] [D] [zway] [07] "SerialAPISetup"
[2017-11-02 12:00:33.117] [D] [zway] [08] "SendNodeInformation"
[2017-11-02 12:00:33.117] [D] [zway] [09] "SendData"
[2017-11-02 12:00:33.117] [D] [zway] [10] "GetVersion"
[2017-11-02 12:00:33.117] [D] [zway] [11] "SendDataAbort"
[2017-11-02 12:00:33.117] [D] [zway] [12] "RFPowerLevelSet"
[2017-11-02 12:00:33.117] [D] [zway] [13] "GetHomeId"
[2017-11-02 12:00:33.117] [D] [zway] [14] "MemoryGetByte"
[2017-11-02 12:00:33.117] [D] [zway] [15] "MemoryPutByte"
[2017-11-02 12:00:33.117] [D] [zway] [16] "MemoryGetBuffer"
[2017-11-02 12:00:33.117] [D] [zway] [17] "MemoryPutBuffer"
[2017-11-02 12:00:33.117] [D] [zway] [18] "FlashAutoProgSet"
[2017-11-02 12:00:33.117] [D] [zway] [19] "NVMGetId"
[2017-11-02 12:00:33.117] [D] [zway] [20] "NVMExtReadLongBuffer"
[2017-11-02 12:00:33.117] [D] [zway] [21] "NVMExtWriteLongBuffer"
[2017-11-02 12:00:33.117] [D] [zway] [22] "NVMExtReadLongByte"
[2017-11-02 12:00:33.117] [D] [zway] [23] "NVMExtWriteLongByte"
[2017-11-02 12:00:33.117] [D] [zway] [24] "ClearNetworkStats"
[2017-11-02 12:00:33.117] [D] [zway] [25] "GetNetworkStats"
[2017-11-02 12:00:33.117] [D] [zway] [26] "GetBackgroundRSSI"
[2017-11-02 12:00:33.117] [D] [zway] [27] "RemoveNodeIdFromNetwork"
[2017-11-02 12:00:33.117] [D] [zway] [28] "GetNodeProtocolInformation"
[2017-11-02 12:00:33.117] [D] [zway] [29] "SetDefault"
[2017-11-02 12:00:33.117] [D] [zway] [30] "ReplicationReceiveComplete"
[2017-11-02 12:00:33.118] [D] [zway] [31] "AssignReturnRoute"
[2017-11-02 12:00:33.118] [D] [zway] [32] "DeleteReturnRoute"
[2017-11-02 12:00:33.118] [D] [zway] [33] "RequestNodeNeighbourUpdate"
[2017-11-02 12:00:33.118] [D] [zway] [34] "ApplicationNodeUpdate"
[2017-11-02 12:00:33.118] [D] [zway] [35] "AddNodeToNetwork"
[2017-11-02 12:00:33.118] [D] [zway] [36] "RemoveNodeFromNetwork"
[2017-11-02 12:00:33.118] [D] [zway] [37] "CreateNewPrimary"
[2017-11-02 12:00:33.118] [D] [zway] [38] "ControllerChange"
[2017-11-02 12:00:33.118] [D] [zway] [39] "SetLearnMode"
[2017-11-02 12:00:33.118] [D] [zway] [40] "AssignSUCReturnRoute"
[2017-11-02 12:00:33.118] [D] [zway] [41] "EnableSUC"
[2017-11-02 12:00:33.118] [D] [zway] [42] "RequestNetworkUpdate"
[2017-11-02 12:00:33.118] [D] [zway] [43] "SetSUCNodeId"
[2017-11-02 12:00:33.118] [D] [zway] [44] "DeleteSUCReturnRoute"
[2017-11-02 12:00:33.118] [D] [zway] [45] "GetSUCNodeId"
[2017-11-02 12:00:33.118] [D] [zway] [46] "SendSUCNodeId"
[2017-11-02 12:00:33.118] [D] [zway] [47] "ExploreRequestInclusion"
[2017-11-02 12:00:33.118] [D] [zway] [48] "RequestNodeInformation"
[2017-11-02 12:00:33.118] [D] [zway] [49] "RemoveFailedNode"
[2017-11-02 12:00:33.118] [D] [zway] [50] "IsFailedNode"
[2017-11-02 12:00:33.118] [D] [zway] [51] "ReplaceFailedNode"
[2017-11-02 12:00:33.118] [D] [zway] [52] "GetRoutingTableLine"
[2017-11-02 12:00:33.118] [D] [zway] [53] "GetPriorityRoute"
[2017-11-02 12:00:33.118] [D] [zway] [54] "SetPriorityRoute"
[2017-11-02 12:00:33.118] [D] [zway] [55] "RFPowerLevelGet"
[2017-11-02 12:00:33.118] [D] [zway] [56] "SendTestFrame"
[2017-11-02 12:00:33.118] [D] [zway] [57] "SetPromiscuousMode"
[2017-11-02 12:00:33.118] [D] [zway] [58] "PromiscuousCommandHandler"
[2017-11-02 12:00:33.119] [D] [zway] [59] "WatchDogStart"
[2017-11-02 12:00:33.119] [D] [zway] [60] "WatchDogStop"
[2017-11-02 12:00:33.119] [D] [zway] [61] "ZMEFreqChange"
[2017-11-02 12:00:33.119] [D] [zway] [62] "ZMERestore"
[2017-11-02 12:00:33.119] [D] [zway] [63] "ZMEBootloaderFlash"
[2017-11-02 12:00:33.119] [D] [zway] [64] "ZMECapabilities"
[2017-11-02 12:00:33.119] [D] [zway] Opened device: /dev/ttyAMA0
[2017-11-02 12:00:33.119] [D] [zway] Worker thread successfully created
[2017-11-02 12:00:33.119] [D] [zway] Worker thread entry point
[2017-11-02 12:00:33.120] [I] [zway] Adding job: Get controller info and supported function classes
[2017-11-02 12:00:33.129] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-11-02 12:00:33.331] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-11-02 12:00:33.331] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-11-02 12:00:33.534] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-11-02 12:00:33.534] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-11-02 12:00:33.736] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-11-02 12:00:33.736] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2017-11-02 12:00:33.736] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2017-11-02 12:00:33.736] [C] [zway] Get Serial API Capabilities returned zero.
[2017-11-02 12:00:33.737] [I] [zway] Removing job: Get controller info and supported function classes
[2017-11-02 12:00:33.747] [D] [zway] Worker thread exit point
[2017-11-02 12:00:33.747] [D] [zway] Worker thread successfully finished
[2017-11-02 12:00:33.748] [I] [core] Restarting Z-Wave binding (zway)
[2017-11-02 12:00:33.750] [I] [core] Error: Binding with name 'zway' already exists
[2017-11-02 12:00:33.751] [I] [core] Trying to restart Z-Wave binding (zway) in 10 seconds
[2017-11-02 12:00:33.762] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2017-11-02 12:00:33.800] [I] [core] Error: Bad data - communication failed
[2017-11-02 12:00:33.805] [I] [core] Tried 5 times without success. Stopping tries.
[2017-11-02 12:00:33.819] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

I've read this from the KNOWN PROBLEMS:

I just got my RaZberry, but it does not work
If you see in the log on of these lines:

Opened device: /dev/ttyAMA0
.....
Notification: critical (z-wave): Can not start Z-Wave binding: Error: Bad data - communication failed

First make sure the LED goes on for 1 second on RaZberry board power on. If not, the board is defective. If it goes on, but then flickers or stay on for long time, the board was damaged. (RaZberry based on 3rd generation chip do not do this self test and does not turn on LED at all). Hope you have a working RaZberry board and the problem is not in the hardware.

Then power cycle your box (not just reboot, but unplug power for few seconds!)

Did not helped? Your Raspberry Pi does not see RaZberry on ttyAMA0. Two possible reasons:

Wrong pins: RaZberry should be installed as on the image in the manual (like shown on the front page of this site).
Configuration of ttyAMA0 went wrong during installation. Check /etc/inittab and /boot/cmdline.txt to have all mentions of ttyAMA0 commented

• During the boot both LEDs are on for aprox 1sec (green and red - I think).

• Board is Gen5 EU.

• Pins are like in the picture, the last 10 or so.

• Check "/etc/inittab " no such folder in my Pi.

• /boot/cmdline.txt => "dwc_otg.lpm_enable=0 console=tty1 root=/dev/mmcblk0p7 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait splash plymouth.ignore-serial-consoles"

Hi, lot of information from your side, thanks.
If it would be my problem, i would change the image from stretch to jessie, as there are known problems,
even if you change some configurations.
I only see error messages regarding your /dev/ttyAMA0 so this would be the first thing to check.

I wonder if there's anyone who can help with this part:

Wrong pins: RaZberry should be installed as on the image in the manual (like shown on the front page of this site).
Configuration of ttyAMA0 went wrong during installation. Check /etc/inittab and /boot/cmdline.txt to have all mentions of ttyAMA0 commented

Like mentioned, I don't have folder /etc/inittab (or at least cannot find it).

And in the cmdline.txt there's not mentioning of ttyAMA0 in it.

What does this mean? What to do next to debug this "known issue"?

add to file cmdline.txt :

console=tty1


inittab is a file not a folder !!

Hi pierre2302, the command line content was in a copy paste above, it does contain the set chars that you were referring to.

Additionally, I've taken the .img from razberry site, and installed it into clean SD, still the same problem. When I try to configure the Z-Wave component in the home automation app, it doesn't seem to work ether.

This would start to look like a broken chip?

It seems that the chip was faulty, I received new one from the supplier, and after adding it - things started to work like a charm!