z-way-server won't start, failed to discover...
Posted: 23 Nov 2014 03:29
Bad configuration or bad RaZberry? Here's the data...
after running wget -q -O - http://razberry.z-wave.me/install | sudo bash
and doing my best (new here) to make sure I have the latest lubuntu updates.
>/etc/init.d/z-way-server start
Starting z-way-server: done.
root@lemaker:/opt/z-way-server# Z-Way version v1.7.2 from 2014-07-26 15:20:00 +0400 (build 428d48e765a32b39615c073af93e8666497724c9)
Using default configuration file config.xml
Starting Z-Way for device /dev/ttyS0...
failed to discover ZWay: Wrong packet from Z-Wave network or Discovery got bad data (stick communication failed) (-9)
...
cat config.xml
<config>
<device>/dev/ttyS0</device>
<config-dir></config-dir>
<translations-dir></translations-dir>
<zddx-dir></zddx-dir>
<port>8083</port>
<http-root-dir>./htdocs</http-root-dir>
<automation-dir>automation</automation-dir>
<shell-script-on-save-xml>sync</shell-script-on-save-xml>
<shell-script-on-save-http>sync</shell-script-on-save-http>
<log-file>/var/log/z-way-server.log</log-file>
<log-level>0</log-level>
<http-log-file>/var/log/z-way-server.log</http-log-file>
<http-log-level>0</http-log-level>
</config>
---
>cat z-way-server.log
[moderator edit] log placed in code block
after running wget -q -O - http://razberry.z-wave.me/install | sudo bash
and doing my best (new here) to make sure I have the latest lubuntu updates.
>/etc/init.d/z-way-server start
Starting z-way-server: done.
root@lemaker:/opt/z-way-server# Z-Way version v1.7.2 from 2014-07-26 15:20:00 +0400 (build 428d48e765a32b39615c073af93e8666497724c9)
Using default configuration file config.xml
Starting Z-Way for device /dev/ttyS0...
failed to discover ZWay: Wrong packet from Z-Wave network or Discovery got bad data (stick communication failed) (-9)
...
cat config.xml
<config>
<device>/dev/ttyS0</device>
<config-dir></config-dir>
<translations-dir></translations-dir>
<zddx-dir></zddx-dir>
<port>8083</port>
<http-root-dir>./htdocs</http-root-dir>
<automation-dir>automation</automation-dir>
<shell-script-on-save-xml>sync</shell-script-on-save-xml>
<shell-script-on-save-http>sync</shell-script-on-save-http>
<log-file>/var/log/z-way-server.log</log-file>
<log-level>0</log-level>
<http-log-file>/var/log/z-way-server.log</http-log-file>
<http-log-level>0</http-log-level>
</config>
---
>cat z-way-server.log
Code: Select all
[2014-11-23 08:18:25.823] SETDATA controller.data = Empty
[2014-11-23 08:18:25.824] SETDATA controller.data.nodeId = Empty
[2014-11-23 08:18:25.824] SETDATA controller.data.homeId = Empty
[2014-11-23 08:18:25.824] SETDATA controller.data.SUCNodeId = Empty
[2014-11-23 08:18:25.824] SETDATA controller.data.isPrimary = Empty
[2014-11-23 08:18:25.824] SETDATA controller.data.isInOthersNetwork = Empty
[2014-11-23 08:18:25.824] SETDATA controller.data.isRealPrimary = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.isSUC = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.SISPresent = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.libType = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.SDK = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.ZWlibMajor = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.ZWlibMinor = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.ZWVersion = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.ZWaveChip = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.APIVersion = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.manufacturerId = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.vendor = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.manufacturerProductType = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.manufacturerProductId = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.capabilities = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.controllerState = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.nonManagmentJobs = Empty
[2014-11-23 08:18:25.825] SETDATA controller.data.lastIncludedDevice = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.lastExcludedDevice = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.secureInclusion = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.oldSerialAPIAckTimeout10ms = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.oldSerialAPIByteTimeout10ms = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.curSerialAPIAckTimeout10ms = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.curSerialAPIByteTimeout10ms = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.libVersionId = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.countJobs = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.memoryGetAddress = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.memoryGetData = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.memoryManufacturerId = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.memoryType = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.memoryCapacity = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.functionClasses = Empty
[2014-11-23 08:18:25.826] SETDATA controller.data.functionClassesNames = Empty
[2014-11-23 08:18:25.827] SETDATA controller.data.softwareRevisionVersion = Empty
[2014-11-23 08:18:25.827] SETDATA controller.data.softwareRevisionId = Empty
[2014-11-23 08:18:25.827] SETDATA controller.data.softwareRevisionDate = Empty
[2014-11-23 08:18:25.827] SETDATA controller.data.nodeId = 0 (0x00000000)
[2014-11-23 08:18:25.827] SETDATA controller.data.homeId = 0 (0x00000000)
[2014-11-23 08:18:25.827] SETDATA controller.data.SUCNodeId = 0 (0x00000000)
[2014-11-23 08:18:25.827] SETDATA controller.data.isPrimary = False
[2014-11-23 08:18:25.827] SETDATA controller.data.isInOthersNetwork = False
[2014-11-23 08:18:25.827] SETDATA controller.data.isRealPrimary = False
[2014-11-23 08:18:25.827] SETDATA controller.data.isSUC = False
[2014-11-23 08:18:25.827] SETDATA controller.data.SISPresent = False
[2014-11-23 08:18:25.827] SETDATA controller.data.libType = 0 (0x00000000)
[2014-11-23 08:18:25.827] SETDATA controller.data.ZWlibMajor = 0 (0x00000000)
[2014-11-23 08:18:25.827] SETDATA controller.data.ZWlibMinor = 0 (0x00000000)
[2014-11-23 08:18:25.827] SETDATA controller.data.ZWVersion = 0 (0x00000000)
[2014-11-23 08:18:25.827] SETDATA controller.data.manufacturerId = 0 (0x00000000)
[2014-11-23 08:18:25.827] SETDATA controller.data.manufacturerProductType = 0 (0x00000000)
[2014-11-23 08:18:25.828] SETDATA controller.data.manufacturerProductId = 0 (0x00000000)
[2014-11-23 08:18:25.828] SETDATA controller.data.controllerState = 0 (0x00000000)
[2014-11-23 08:18:25.828] SETDATA controller.data.nonManagmentJobs = 0 (0x00000000)
[2014-11-23 08:18:25.828] SETDATA controller.data.secureInclusion = True
[2014-11-23 08:18:25.828] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 0 (0x00000000)
[2014-11-23 08:18:25.828] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 0 (0x00000000)
[2014-11-23 08:18:25.828] SETDATA controller.data.curSerialAPIAckTimeout10ms = 0 (0x00000000)
[2014-11-23 08:18:25.828] SETDATA controller.data.curSerialAPIByteTimeout10ms = 0 (0x00000000)
[2014-11-23 08:18:25.828] SETDATA controller.data.libVersionId = 1 (0x00000001)
[2014-11-23 08:18:25.828] SETDATA controller.data.countJobs = False
[2014-11-23 08:18:25.828] SETDATA controller.data.softwareRevisionVersion = "v1.7.2"
[2014-11-23 08:18:25.828] SETDATA controller.data.softwareRevisionId = "428d48e765a32b39615c073af93e8666497724c9"
[2014-11-23 08:18:25.828] SETDATA controller.data.softwareRevisionDate = "2014-07-26 15:20:00 +0400"
[2014-11-23 08:18:25.829] SETDATA controller.data.functionClasses = int[48]
[2014-11-23 08:18:25.829] [00] 2
[2014-11-23 08:18:25.829] [01] 3
[2014-11-23 08:18:25.829] [02] 4
[2014-11-23 08:18:25.829] [03] 5
[2014-11-23 08:18:25.829] [04] 6
[2014-11-23 08:18:25.829] [05] 7
[2014-11-23 08:18:25.829] [06] 8
[2014-11-23 08:18:25.829] [07] 18
[2014-11-23 08:18:25.829] [08] 19
[2014-11-23 08:18:25.829] [09] 21
[2014-11-23 08:18:25.829] [10] 22
[2014-11-23 08:18:25.829] [11] 32
[2014-11-23 08:18:25.829] [12] 33
[2014-11-23 08:18:25.829] [13] 34
[2014-11-23 08:18:25.829] [14] 35
[2014-11-23 08:18:25.830] [15] 36
[2014-11-23 08:18:25.830] [16] 41
[2014-11-23 08:18:25.830] [17] 42
[2014-11-23 08:18:25.830] [18] 43
[2014-11-23 08:18:25.830] [19] 44
[2014-11-23 08:18:25.830] [20] 45
[2014-11-23 08:18:25.830] [21] 65
[2014-11-23 08:18:25.830] [22] 66
[2014-11-23 08:18:25.830] [23] 70
[2014-11-23 08:18:25.830] [24] 71
[2014-11-23 08:18:25.830] [25] 72
[2014-11-23 08:18:25.830] [26] 73
[2014-11-23 08:18:25.830] [27] 74
[2014-11-23 08:18:25.830] [28] 75
[2014-11-23 08:18:25.830] [29] 76
[2014-11-23 08:18:25.830] [30] 77
[2014-11-23 08:18:25.830] [31] 80
[2014-11-23 08:18:25.830] [32] 81
[2014-11-23 08:18:25.830] [33] 82
[2014-11-23 08:18:25.830] [34] 83
[2014-11-23 08:18:25.831] [35] 84
[2014-11-23 08:18:25.831] [36] 85
[2014-11-23 08:18:25.831] [37] 86
[2014-11-23 08:18:25.831] [38] 87
[2014-11-23 08:18:25.831] [39] 96
[2014-11-23 08:18:25.831] [40] 97
[2014-11-23 08:18:25.831] [41] 98
[2014-11-23 08:18:25.831] [42] 99
[2014-11-23 08:18:25.831] [43] 128
[2014-11-23 08:18:25.831] [44] 210
[2014-11-23 08:18:25.831] [45] 211
[2014-11-23 08:18:25.831] [46] 242
[2014-11-23 08:18:25.831] [47] 243
[2014-11-23 08:18:25.831] SETDATA controller.data.functionClassesNames = string[48]
[2014-11-23 08:18:25.831] [00] "SerialAPIGetInitData"
[2014-11-23 08:18:25.831] [01] "SerialAPIApplicationNodeInformation"
[2014-11-23 08:18:25.831] [02] "ApplicationCommandHandler"
[2014-11-23 08:18:25.832] [03] "GetControllerCapabilities"
[2014-11-23 08:18:25.832] [04] "SerialAPISetTimeouts"
[2014-11-23 08:18:25.832] [05] "GetSerialAPICapabilities"
[2014-11-23 08:18:25.832] [06] "SerialAPISoftReset"
[2014-11-23 08:18:25.832] [07] "SendNodeInformation"
[2014-11-23 08:18:25.832] [08] "SendData"
[2014-11-23 08:18:25.832] [09] "GetVersion"
[2014-11-23 08:18:25.832] [10] "SendDataAbort"
[2014-11-23 08:18:25.832] [11] "GetHomeId"
[2014-11-23 08:18:25.832] [12] "MemoryGetByte"
[2014-11-23 08:18:25.832] [13] "MemoryPutByte"
[2014-11-23 08:18:25.832] [14] "MemoryGetBuffer"
[2014-11-23 08:18:25.832] [15] "MemoryPutBuffer"
[2014-11-23 08:18:25.832] [16] "NVMGetId"
[2014-11-23 08:18:25.832] [17] "NVMExtReadLongBuffer"
[2014-11-23 08:18:25.832] [18] "NVMExtWriteLongBuffer"
[2014-11-23 08:18:25.832] [19] "NVMExtReadLongByte"
[2014-11-23 08:18:25.832] [20] "NVMExtWriteLongByte"
[2014-11-23 08:18:25.833] [21] "GetNodeProtocolInformation"
[2014-11-23 08:18:25.833] [22] "SetDefault"
[2014-11-23 08:18:25.833] [23] "AssignReturnRoute"
[2014-11-23 08:18:25.833] [24] "DeleteReturnRoute"
[2014-11-23 08:18:25.833] [25] "RequestNodeNeighbourUpdate"
[2014-11-23 08:18:25.833] [26] "ApplicationNodeUpdate"
[2014-11-23 08:18:25.833] [27] "AddNodeToNetwork"
[2014-11-23 08:18:25.833] [28] "RemoveNodeFromNetwork"
[2014-11-23 08:18:25.833] [29] "CreateNewPrimary"
[2014-11-23 08:18:25.833] [30] "ControllerChange"
[2014-11-23 08:18:25.833] [31] "SetLearnMode"
[2014-11-23 08:18:25.833] [32] "AssignSUCReturnRoute"
[2014-11-23 08:18:25.833] [33] "EnableSUC"
[2014-11-23 08:18:25.833] [34] "RequestNetworkUpdate"
[2014-11-23 08:18:25.833] [35] "SetSUCNodeId"
[2014-11-23 08:18:25.833] [36] "DeleteSUCReturnRoute"
[2014-11-23 08:18:25.833] [37] "GetSUCNodeId"
[2014-11-23 08:18:25.833] [38] "SendSUCNodeId"
[2014-11-23 08:18:25.833] [39] "RequestNodeInformation"
[2014-11-23 08:18:25.833] [40] "RemoveFailedNode"
[2014-11-23 08:18:25.834] [41] "IsFailedNode"
[2014-11-23 08:18:25.834] [42] "ReplaceFailedNode"
[2014-11-23 08:18:25.834] [43] "GetRoutingTableLine"
[2014-11-23 08:18:25.834] [44] "WatchDogStart"
[2014-11-23 08:18:25.834] [45] "WatchDogStop"
[2014-11-23 08:18:25.834] [46] "ZMEFreqChange"
[2014-11-23 08:18:25.834] [47] "ZMERestore"
[2014-11-23 08:18:25.834] Opened device: /dev/ttyS0
[2014-11-23 08:18:25.835] Worker thread successfully created
[2014-11-23 08:18:25.835] Worker thread entry point
[2014-11-23 08:18:25.845] Adding job: Get controller info and supported function classes
[2014-11-23 08:18:25.848] SENDING (cb 0x07): ( 01 03 00 07 FB )
[2014-11-23 08:18:26.027] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2014-11-23 08:18:26.038] SENDING (cb 0x07): ( 01 03 00 07 FB )
[2014-11-23 08:18:26.217] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2014-11-23 08:18:26.229] SENDING (cb 0x07): ( 01 03 00 07 FB )
[2014-11-23 08:18:26.408] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2014-11-23 08:18:26.408] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2014-11-23 08:18:26.408] Job 0x07 (Get controller info and supported function classes): fail
[2014-11-23 08:18:26.409] Get Serial API Capabilities returned zero.
[2014-11-23 08:18:26.409] Removing job: Get controller info and supported function classes
[2014-11-23 08:18:26.419] Worker thread exit point
[2014-11-23 08:18:26.419] Worker thread successfully finished