Just bought the board, plugged it in as instructed. UI works but everything is empty. looks like there is no communication with the board. I have Raspberry Pi 3.
Logs in the attachment.
Most important thing is:
[core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed
When I run:
sudo fuser /dev/ttyAMA0
nothing is shown. it just show a new command line. So it looks like the software is not communicating with the board right? what to do?
Thanks
(sorry, can't attach any files. so appending the logs to this text box)
Code: Select all
[2017-01-01 23:00:58.560] [I] [core] Executing script: /*** Z-Way Home Automation Engine main executable ***************************** ...
[2017-01-01 23:00:58.600] [I] [core] Executing script: // Comon utilities and functions ...
[2017-01-01 23:00:58.659] [I] [core] Executing script: // This script transforms old formats to new ...
[2017-01-01 23:00:58.861] [I] [core] Executing script: /*! ...
[2017-01-01 23:00:58.927] [I] [core] Executing script: // Underscore.js 1.7.0 ...
[2017-01-01 23:00:58.991] [I] [core] Executing script: /*! ...
[2017-01-01 23:00:59.032] [I] [core] Executing script: ...
[2017-01-01 23:00:59.089] [I] [core] Executing script: /*** Z-Way HA Virtual Device base class *************************************** ...
[2017-01-01 23:00:59.148] [I] [core] Executing script: /*** Z-Way DevicesCollection class ************************************ ...
[2017-01-01 23:00:59.239] [I] [core] Executing script: /*** Z-Way HA Controller class module ***************************************** ...
[2017-01-01 23:00:59.290] [I] [core] Executing script: /*** Automation Webserver Auth Controller ************************************* ...
[2017-01-01 23:00:59.312] [I] [core] Executing script: /*** Z-Way HA Automation module base class ************************************ ...
[2017-01-01 23:00:59.322] [I] [core] Executing script: ...
[2017-01-01 23:00:59.364] [I] [core] Executing script: /*** Main Automation storage module ***************************************** ...
[2017-01-01 23:00:59.583] [I] [core] Executing script: /*** Main Automation webserver module ***************************************** ...
[2017-01-01 23:00:59.603] [I] [core] Executing script: // Make this testable by pulling in underscore from the parent module ...
[2017-01-01 23:00:59.721] [I] [core] Executing script: /*** Main Automation storage module ***************************************** ...
[2017-01-01 23:00:59.754] [I] [core] --- Loading ZAutomation classes
[2017-01-01 23:01:01.196] [I] [core] Starting ZWay Automation webserver
[2017-01-01 23:01:01.217] [I] [core] Loading modules...
[2017-01-01 23:01:01.239] [I] [core] Loading module ZWave from modules/ZWave
[2017-01-01 23:01:01.274] [I] [core] Executing script: /*** Z-Wave Binding module ******************************************************** ...
[2017-01-01 23:01:01.319] [I] [core] Instantiating module 1 from class ZWave
[2017-01-01 23:01:01.326] [I] [core] --- Starting module Z-Wave Network Access
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.nodeId = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.nodeId = 0 (0x00000000)
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.homeId = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.homeId = 0 (0x00000000)
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.SUCNodeId = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.SUCNodeId = 0 (0x00000000)
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.isPrimary = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.isPrimary = False
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.isInOthersNetwork = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.isInOthersNetwork = False
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.isRealPrimary = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.isRealPrimary = False
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.isSUC = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.isSUC = False
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.SISPresent = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.SISPresent = False
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.libType = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.libType = ""
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.SDK = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.SDK = ""
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.ZWlibMajor = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.ZWlibMajor = 0 (0x00000000)
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.ZWlibMinor = Empty
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.ZWlibMinor = 0 (0x00000000)
[2017-01-01 23:01:01.996] [D] [zway] SETDATA controller.data.ZWLib = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.ZWLib = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.ZWVersion = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.ZWVersion = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.ZWaveChip = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.ZWaveChip = ""
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.APIVersion = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.APIVersion = ""
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.manufacturerId = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.manufacturerId = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.vendor = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.vendor = ""
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.manufacturerProductType = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.manufacturerProductType = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.manufacturerProductId = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.manufacturerProductId = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.bootloaderCRC = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.firmwareCRC = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.capabilities = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.controllerState = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.nonManagmentJobs = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.nonManagmentJobs = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.lastIncludedDevice = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.lastIncludedDevice = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.lastExcludedDevice = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.lastExcludedDevice = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.secureInclusion = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.secureInclusion = True
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = 0 (0x00000000)
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.countJobs = Empty
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.countJobs = False
[2017-01-01 23:01:01.997] [D] [zway] SETDATA controller.data.memoryGetAddress = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.memoryGetData = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.functionClasses = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.functionClassesNames = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.softwareRevisionVersion = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.softwareRevisionVersion = "v2.2.5"
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.softwareRevisionId = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.softwareRevisionId = "d58b7617c4f553beca4c6f41705b48fa5a4cf56c"
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.softwareRevisionDate = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.softwareRevisionDate = "2016-11-15 11:42:15 +0300"
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.uuid = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.caps = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.frequency = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.frequency = ""
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.deviceRelaxDelay = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.deviceRelaxDelay = 5 (0x00000005)
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.incomingPacket = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.incomingPacket.nodeId = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.incomingPacket.RSSI = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.incomingPacket.frameType = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket.delivered = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket.deliveryTime = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket.packetLength = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket.nodeId = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket.returnRSSI = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket.hops = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket.returnChannel = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket.txChannel = Empty
[2017-01-01 23:01:01.998] [D] [zway] SETDATA controller.data.outgoingPacket.speed = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.outgoingPacket.schemeState = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.outgoingPacket.tries = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.outgoingPacket.lastFailPath = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = 127 (0x0000007f)
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = 127 (0x0000007f)
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = 127 (0x0000007f)
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = 0 (0x00000000)
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = 0 (0x00000000)
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = 0 (0x00000000)
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = 0 (0x00000000)
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = 0 (0x00000000)
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = 0 (0x00000000)
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.priorityRoute = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.priorityRoute.dstNodeId = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.priorityRoute.routeType = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.priorityRoute.speed = Empty
[2017-01-01 23:01:01.999] [D] [zway] SETDATA controller.data.statistics.priorityRoute.hops = Empty
[2017-01-01 23:01:02.000] [D] [zway] SETDATA controller.data.functionClasses = int[62]
[2017-01-01 23:01:02.000] [D] [zway] [00] 2
[2017-01-01 23:01:02.000] [D] [zway] [01] 3
[2017-01-01 23:01:02.000] [D] [zway] [02] 4
[2017-01-01 23:01:02.000] [D] [zway] [03] 5
[2017-01-01 23:01:02.000] [D] [zway] [04] 6
[2017-01-01 23:01:02.000] [D] [zway] [05] 7
[2017-01-01 23:01:02.000] [D] [zway] [06] 8
[2017-01-01 23:01:02.000] [D] [zway] [07] 11
[2017-01-01 23:01:02.000] [D] [zway] [08] 18
[2017-01-01 23:01:02.000] [D] [zway] [09] 19
[2017-01-01 23:01:02.000] [D] [zway] [10] 21
[2017-01-01 23:01:02.000] [D] [zway] [11] 22
[2017-01-01 23:01:02.000] [D] [zway] [12] 23
[2017-01-01 23:01:02.000] [D] [zway] [13] 32
[2017-01-01 23:01:02.000] [D] [zway] [14] 33
[2017-01-01 23:01:02.000] [D] [zway] [15] 34
[2017-01-01 23:01:02.000] [D] [zway] [16] 35
[2017-01-01 23:01:02.000] [D] [zway] [17] 36
[2017-01-01 23:01:02.000] [D] [zway] [18] 39
[2017-01-01 23:01:02.000] [D] [zway] [19] 41
[2017-01-01 23:01:02.000] [D] [zway] [20] 42
[2017-01-01 23:01:02.000] [D] [zway] [21] 43
[2017-01-01 23:01:02.000] [D] [zway] [22] 44
[2017-01-01 23:01:02.000] [D] [zway] [23] 45
[2017-01-01 23:01:02.000] [D] [zway] [24] 57
[2017-01-01 23:01:02.000] [D] [zway] [25] 58
[2017-01-01 23:01:02.000] [D] [zway] [26] 59
[2017-01-01 23:01:02.000] [D] [zway] [27] 65
[2017-01-01 23:01:02.000] [D] [zway] [28] 66
[2017-01-01 23:01:02.000] [D] [zway] [29] 68
[2017-01-01 23:01:02.000] [D] [zway] [30] 70
[2017-01-01 23:01:02.000] [D] [zway] [31] 71
[2017-01-01 23:01:02.000] [D] [zway] [32] 72
[2017-01-01 23:01:02.000] [D] [zway] [33] 73
[2017-01-01 23:01:02.000] [D] [zway] [34] 74
[2017-01-01 23:01:02.000] [D] [zway] [35] 75
[2017-01-01 23:01:02.000] [D] [zway] [36] 76
[2017-01-01 23:01:02.000] [D] [zway] [37] 77
[2017-01-01 23:01:02.000] [D] [zway] [38] 80
[2017-01-01 23:01:02.000] [D] [zway] [39] 81
[2017-01-01 23:01:02.000] [D] [zway] [40] 82
[2017-01-01 23:01:02.000] [D] [zway] [41] 83
[2017-01-01 23:01:02.000] [D] [zway] [42] 84
[2017-01-01 23:01:02.000] [D] [zway] [43] 85
[2017-01-01 23:01:02.000] [D] [zway] [44] 86
[2017-01-01 23:01:02.001] [D] [zway] [45] 87
[2017-01-01 23:01:02.001] [D] [zway] [46] 94
[2017-01-01 23:01:02.001] [D] [zway] [47] 96
[2017-01-01 23:01:02.001] [D] [zway] [48] 97
[2017-01-01 23:01:02.001] [D] [zway] [49] 98
[2017-01-01 23:01:02.001] [D] [zway] [50] 99
[2017-01-01 23:01:02.001] [D] [zway] [51] 128
[2017-01-01 23:01:02.001] [D] [zway] [52] 146
[2017-01-01 23:01:02.001] [D] [zway] [53] 147
[2017-01-01 23:01:02.001] [D] [zway] [54] 186
[2017-01-01 23:01:02.001] [D] [zway] [55] 190
[2017-01-01 23:01:02.001] [D] [zway] [56] 210
[2017-01-01 23:01:02.001] [D] [zway] [57] 211
[2017-01-01 23:01:02.001] [D] [zway] [58] 242
[2017-01-01 23:01:02.001] [D] [zway] [59] 243
[2017-01-01 23:01:02.001] [D] [zway] [60] 244
[2017-01-01 23:01:02.001] [D] [zway] [61] 245
[2017-01-01 23:01:02.001] [D] [zway] SETDATA controller.data.functionClassesNames = string[62]
[2017-01-01 23:01:02.001] [D] [zway] [00] "SerialAPIGetInitData"
[2017-01-01 23:01:02.001] [D] [zway] [01] "SerialAPIApplicationNodeInformation"
[2017-01-01 23:01:02.001] [D] [zway] [02] "ApplicationCommandHandler"
[2017-01-01 23:01:02.001] [D] [zway] [03] "GetControllerCapabilities"
[2017-01-01 23:01:02.001] [D] [zway] [04] "SerialAPISetTimeouts"
[2017-01-01 23:01:02.001] [D] [zway] [05] "GetSerialAPICapabilities"
[2017-01-01 23:01:02.001] [D] [zway] [06] "SerialAPISoftReset"
[2017-01-01 23:01:02.001] [D] [zway] [07] "SerialAPISetup"
[2017-01-01 23:01:02.001] [D] [zway] [08] "SendNodeInformation"
[2017-01-01 23:01:02.001] [D] [zway] [09] "SendData"
[2017-01-01 23:01:02.001] [D] [zway] [10] "GetVersion"
[2017-01-01 23:01:02.001] [D] [zway] [11] "SendDataAbort"
[2017-01-01 23:01:02.001] [D] [zway] [12] "RFPowerLevelSet"
[2017-01-01 23:01:02.001] [D] [zway] [13] "GetHomeId"
[2017-01-01 23:01:02.001] [D] [zway] [14] "MemoryGetByte"
[2017-01-01 23:01:02.001] [D] [zway] [15] "MemoryPutByte"
[2017-01-01 23:01:02.001] [D] [zway] [16] "MemoryGetBuffer"
[2017-01-01 23:01:02.001] [D] [zway] [17] "MemoryPutBuffer"
[2017-01-01 23:01:02.002] [D] [zway] [18] "FlashAutoProgSet"
[2017-01-01 23:01:02.002] [D] [zway] [19] "NVMGetId"
[2017-01-01 23:01:02.002] [D] [zway] [20] "NVMExtReadLongBuffer"
[2017-01-01 23:01:02.002] [D] [zway] [21] "NVMExtWriteLongBuffer"
[2017-01-01 23:01:02.002] [D] [zway] [22] "NVMExtReadLongByte"
[2017-01-01 23:01:02.002] [D] [zway] [23] "NVMExtWriteLongByte"
[2017-01-01 23:01:02.002] [D] [zway] [24] "ClearNetworkStats"
[2017-01-01 23:01:02.002] [D] [zway] [25] "GetNetworkStats"
[2017-01-01 23:01:02.002] [D] [zway] [26] "GetBackgroundRSSI"
[2017-01-01 23:01:02.002] [D] [zway] [27] "GetNodeProtocolInformation"
[2017-01-01 23:01:02.002] [D] [zway] [28] "SetDefault"
[2017-01-01 23:01:02.002] [D] [zway] [29] "ReplicationReceiveComplete"
[2017-01-01 23:01:02.002] [D] [zway] [30] "AssignReturnRoute"
[2017-01-01 23:01:02.002] [D] [zway] [31] "DeleteReturnRoute"
[2017-01-01 23:01:02.002] [D] [zway] [32] "RequestNodeNeighbourUpdate"
[2017-01-01 23:01:02.002] [D] [zway] [33] "ApplicationNodeUpdate"
[2017-01-01 23:01:02.002] [D] [zway] [34] "AddNodeToNetwork"
[2017-01-01 23:01:02.002] [D] [zway] [35] "RemoveNodeFromNetwork"
[2017-01-01 23:01:02.002] [D] [zway] [36] "CreateNewPrimary"
[2017-01-01 23:01:02.002] [D] [zway] [37] "ControllerChange"
[2017-01-01 23:01:02.002] [D] [zway] [38] "SetLearnMode"
[2017-01-01 23:01:02.002] [D] [zway] [39] "AssignSUCReturnRoute"
[2017-01-01 23:01:02.002] [D] [zway] [40] "EnableSUC"
[2017-01-01 23:01:02.002] [D] [zway] [41] "RequestNetworkUpdate"
[2017-01-01 23:01:02.002] [D] [zway] [42] "SetSUCNodeId"
[2017-01-01 23:01:02.002] [D] [zway] [43] "DeleteSUCReturnRoute"
[2017-01-01 23:01:02.002] [D] [zway] [44] "GetSUCNodeId"
[2017-01-01 23:01:02.002] [D] [zway] [45] "SendSUCNodeId"
[2017-01-01 23:01:02.002] [D] [zway] [46] "ExploreRequestInclusion"
[2017-01-01 23:01:02.002] [D] [zway] [47] "RequestNodeInformation"
[2017-01-01 23:01:02.002] [D] [zway] [48] "RemoveFailedNode"
[2017-01-01 23:01:02.002] [D] [zway] [49] "IsFailedNode"
[2017-01-01 23:01:02.002] [D] [zway] [50] "ReplaceFailedNode"
[2017-01-01 23:01:02.002] [D] [zway] [51] "GetRoutingTableLine"
[2017-01-01 23:01:02.002] [D] [zway] [52] "GetPriorityRoute"
[2017-01-01 23:01:02.002] [D] [zway] [53] "SetPriorityRoute"
[2017-01-01 23:01:02.002] [D] [zway] [54] "RFPowerLevelGet"
[2017-01-01 23:01:02.002] [D] [zway] [55] "SendTestFrame"
[2017-01-01 23:01:02.002] [D] [zway] [56] "WatchDogStart"
[2017-01-01 23:01:02.002] [D] [zway] [57] "WatchDogStop"
[2017-01-01 23:01:02.002] [D] [zway] [58] "ZMEFreqChange"
[2017-01-01 23:01:02.002] [D] [zway] [59] "ZMERestore"
[2017-01-01 23:01:02.002] [D] [zway] [60] "ZMEBootloaderFlash"
[2017-01-01 23:01:02.002] [D] [zway] [61] "ZMECapabilities"
[2017-01-01 23:01:02.003] [D] [zway] Opened device: /dev/ttyAMA0
[2017-01-01 23:01:02.003] [D] [zway] Worker thread successfully created
[2017-01-01 23:01:02.003] [D] [zway] Worker thread entry point
[2017-01-01 23:01:02.008] [I] [zway] Adding job: Get controller info and supported function classes
[2017-01-01 23:01:02.013] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-01-01 23:01:02.221] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-01-01 23:01:02.221] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-01-01 23:01:02.426] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-01-01 23:01:02.426] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-01-01 23:01:02.629] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-01-01 23:01:02.629] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2017-01-01 23:01:02.629] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2017-01-01 23:01:02.629] [C] [zway] Get Serial API Capabilities returned zero.
[2017-01-01 23:01:02.629] [I] [zway] Removing job: Get controller info and supported function classes
[2017-01-01 23:01:02.640] [D] [zway] Worker thread exit point
[2017-01-01 23:01:02.640] [D] [zway] Worker thread successfully finished
[2017-01-01 23:01:02.641] [I] [core] Terminating Z-Wave binding
[2017-01-01 23:01:02.650] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2017-01-01 23:01:02.682] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed
[2017-01-01 23:01:02.686] [I] [core] Loading module Cron from modules/Cron
[2017-01-01 23:01:02.711] [I] [core] Executing script: /*** Cron ZAutomation module ************************************************** ...
[2017-01-01 23:01:02.747] [I] [core] Instantiating module 2 from class Cron
[2017-01-01 23:01:02.749] [I] [core] --- Starting module System Clock (CRON)
[2017-01-01 23:01:02.763] [I] [core] Loading module PhilioHW from modules/PhilioHW
[2017-01-01 23:01:02.807] [I] [core] Executing script: /*** PhilioHW Z-Way HA module ******************************************* ...
[2017-01-01 23:01:02.812] [I] [core] Loading module InbandNotifications from modules/InbandNotifications
[2017-01-01 23:01:02.845] [I] [core] Executing script: /*** InbandNotifications Z-Way HA module ******************************************* ...
[2017-01-01 23:01:02.883] [I] [core] Instantiating module 3 from class InbandNotifications
[2017-01-01 23:01:02.886] [I] [core] --- Starting module Inband Notifier
[2017-01-01 23:01:02.904] [I] [core] Loading module BindDevices from modules/BindDevices
[2017-01-01 23:01:02.923] [I] [core] Executing script: /*** BindDevices Z-Way HA module ******************************************* ...
[2017-01-01 23:01:02.927] [I] [core] Loading module ScheduledScene from modules/ScheduledScene
[2017-01-01 23:01:02.940] [I] [core] Executing script: /*** ScheduledScene Z-Way HA module ******************************************* ...
[2017-01-01 23:01:02.942] [I] [core] Loading module FosCam9805 from modules/FosCam9805
[2017-01-01 23:01:02.966] [I] [core] Executing script: /*** FosCam9805 Z-Way HA module ******************************************* ...
[2017-01-01 23:01:02.970] [I] [core] Loading module RGB from modules/RGB
[2017-01-01 23:01:02.999] [I] [core] Executing script: /*** RGB Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.017] [I] [core] Loading module TagOnOff from modules/TagOnOff
[2017-01-01 23:01:03.028] [I] [core] Executing script: /*** TagOnOff Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.029] [I] [core] Loading module CustomUserCodeLoader from modules/CustomUserCodeLoader
[2017-01-01 23:01:03.037] [I] [core] Executing script: /*** CustomUserCodeLoader ZAutomation module **************************************** ...
[2017-01-01 23:01:03.038] [I] [core] Loading module LightScene from modules/LightScene
[2017-01-01 23:01:03.050] [I] [core] Executing script: /*** LightScene Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.051] [I] [core] Loading module SecurityMode from modules/SecurityMode
[2017-01-01 23:01:03.075] [I] [core] Executing script: /*** SecurityMode Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.077] [I] [core] Loading module LightMotionRockerAutocontrol from modules/LightMotionRockerAutocontrol
[2017-01-01 23:01:03.104] [I] [core] Executing script: /*** LightMotionRockerAutocontrol Z-Way Home Automation module ************************************* ...
[2017-01-01 23:01:03.105] [I] [core] Loading module CustomUserCode from modules/CustomUserCode
[2017-01-01 23:01:03.116] [I] [core] Executing script: /*** CustomUserCode ZAutomation module **************************************** ...
[2017-01-01 23:01:03.117] [I] [core] Loading module GroupDevices from modules/GroupDevices
[2017-01-01 23:01:03.138] [I] [core] Executing script: /*** GroupDevices Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.142] [I] [core] Loading module CodeDevice from modules/CodeDevice
[2017-01-01 23:01:03.162] [I] [core] Executing script: /*** CodeDevice Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.163] [I] [core] Loading module RoundRobinScenes from modules/RoundRobinScenes
[2017-01-01 23:01:03.177] [I] [core] Executing script: /*** RoundRobinScenes Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.179] [I] [core] Loading module Sonos from modules/Sonos
[2017-01-01 23:01:03.215] [I] [core] Executing script: /*** Sonos Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.216] [I] [core] Loading module FosCam9826 from modules/FosCam9826
[2017-01-01 23:01:03.256] [I] [core] Executing script: /*** FosCam9826 Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.257] [I] [core] Loading module IfThen from modules/IfThen
[2017-01-01 23:01:03.309] [I] [core] Executing script: /*** IfThen Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.310] [I] [core] Loading module AutoLock from modules/AutoLock
[2017-01-01 23:01:03.351] [I] [core] Executing script: /*** AutoLock Z-Way Home Automation module ************************************* ...
[2017-01-01 23:01:03.352] [I] [core] Loading module NotificationSMSru from modules/NotificationSMSru
[2017-01-01 23:01:03.387] [I] [core] Executing script: /*** NotificationSMSru Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.388] [I] [core] Loading module SensorsPolling from modules/SensorsPolling
[2017-01-01 23:01:03.454] [I] [core] Executing script: /*** SensorsPolling Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.496] [I] [core] Instantiating module 6 from class SensorsPolling
[2017-01-01 23:01:03.501] [I] [core] --- Starting module Periodical Sensor Polling
[2017-01-01 23:01:03.506] [I] [core] Loading module GlobalCache from modules/GlobalCache
[2017-01-01 23:01:03.518] [I] [core] Executing script: /*** GlobalCache Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.519] [I] [core] Loading module CounterTriggeringSensor from modules/CounterTriggeringSensor
[2017-01-01 23:01:03.543] [I] [core] Executing script: /*** Counter triggering binary sensor Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.544] [I] [core] Loading module LogicalRules from modules/LogicalRules
[2017-01-01 23:01:03.590] [I] [core] Executing script: /*** LogicalRules Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.591] [I] [core] Loading module OpenRemoteHelpers from modules/OpenRemoteHelpers
[2017-01-01 23:01:03.623] [I] [core] Executing script: /* RaZOR ...
[2017-01-01 23:01:03.626] [I] [core] Loading module Notification from modules/Notification
[2017-01-01 23:01:03.646] [I] [core] Executing script: /*** Notification Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.647] [I] [core] Loading module SensorsPollingLogging from modules/SensorsPollingLogging
[2017-01-01 23:01:03.684] [I] [core] Executing script: /*** SensorsPollingLogging Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.685] [I] [core] Loading module PoppCam from modules/PoppCam
[2017-01-01 23:01:03.718] [I] [core] Executing script: /*** PoppCam Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.720] [I] [core] Loading module DeviceHistory from modules/DeviceHistory
[2017-01-01 23:01:03.763] [I] [core] Executing script: /*** DeviceHistory Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.765] [I] [core] Loading module ThermostatDevice from modules/ThermostatDevice
[2017-01-01 23:01:03.781] [I] [core] Executing script: /*** ThermostatDevice Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.782] [I] [core] Loading module FosCam9821 from modules/FosCam9821
[2017-01-01 23:01:03.824] [I] [core] Executing script: /*** FosCam9821 Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.825] [I] [core] Loading module BatteryPolling from modules/BatteryPolling
[2017-01-01 23:01:03.853] [I] [core] Executing script: /*** BatteryPolling Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.884] [I] [core] Instantiating module 7 from class BatteryPolling
[2017-01-01 23:01:03.887] [I] [core] --- Starting module Battery Polling
[2017-01-01 23:01:03.892] [I] [core] Creating device battery BatteryPolling_7
[2017-01-01 23:01:03.925] [I] [core] Loading module SwitchControlGenerator from modules/SwitchControlGenerator
[2017-01-01 23:01:03.954] [I] [core] Executing script: /*** SwitchControlGenerator Z-Way HA module ******************************************* ...
[2017-01-01 23:01:03.981] [I] [core] Instantiating module 8 from class SwitchControlGenerator
[2017-01-01 23:01:03.984] [I] [core] --- Starting module Trap events from Remotes and Sensors
[2017-01-01 23:01:03.987] [I] [core] Loading module ImportRemoteHA from modules/ImportRemoteHA
[2017-01-01 23:01:04.006] [I] [core] Executing script: /*** ImportRemoteHA Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.007] [I] [core] Loading module HTTPDevice from modules/HTTPDevice
[2017-01-01 23:01:04.041] [I] [core] Executing script: /*** HTTPDevice Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.042] [I] [core] Loading module OpenWeather from modules/OpenWeather
[2017-01-01 23:01:04.052] [I] [core] Executing script: /*** OpenWeather Extended Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.053] [I] [core] Loading module InfoWidget from modules/InfoWidget
[2017-01-01 23:01:04.089] [I] [core] Executing script: /*** InfoWidget Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.118] [I] [core] Instantiating module 5 from class InfoWidget
[2017-01-01 23:01:04.119] [I] [core] --- Starting module Information Widget
[2017-01-01 23:01:04.123] [I] [core] Creating device text InfoWidget_5_Int
[2017-01-01 23:01:04.133] [I] [core] Loading module AutoOff from modules/AutoOff
[2017-01-01 23:01:04.149] [I] [core] Executing script: /*** AutoOff Z-Way Home Automation module ************************************* ...
[2017-01-01 23:01:04.150] [I] [core] Loading module CustomUserCodeZWay from modules/CustomUserCodeZWay
[2017-01-01 23:01:04.162] [I] [core] Executing script: /*** CustomUserCodeZWay ZAutomation module **************************************** ...
[2017-01-01 23:01:04.163] [I] [core] Loading module RemoteAccess from modules/RemoteAccess
[2017-01-01 23:01:04.200] [I] [core] Executing script: /*** RemoteAccess Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.239] [I] [core] Instantiating module 4 from class RemoteAccess
[2017-01-01 23:01:04.240] [I] [core] --- Starting module Remote Access
[2017-01-01 23:01:04.258] [I] [core] success! start zbw ...
[2017-01-01 23:01:04.262] [I] [core] Loading module MultilineSensor from modules/MultilineSensor
[2017-01-01 23:01:04.290] [I] [core] Executing script: /*** MultilineSensor Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.291] [I] [core] Loading module DelayedScene from modules/DelayedScene
[2017-01-01 23:01:04.304] [I] [core] Executing script: /*** DelayedScene Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.305] [I] [core] Loading module ZMEOpenWRT from modules/ZMEOpenWRT
[2017-01-01 23:01:04.330] [I] [core] Executing script: /*** ZMEOpenWRT Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.331] [I] [core] Loading module TamperAutoOff from modules/TamperAutoOff
[2017-01-01 23:01:04.365] [I] [core] Executing script: /*** TamperAutoOff Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.366] [I] [core] Loading module SensorValueLogging from modules/SensorValueLogging
[2017-01-01 23:01:04.379] [I] [core] Executing script: /*** SensorValueLogging Z-Way Home Automation module ************************************* ...
[2017-01-01 23:01:04.381] [I] [core] Loading module EnOcean from modules/EnOcean
[2017-01-01 23:01:04.404] [I] [core] Executing script: /*** EnOcean Binding module ******************************************************** ...
[2017-01-01 23:01:04.406] [I] [core] Loading module FosCam9828 from modules/FosCam9828
[2017-01-01 23:01:04.435] [I] [core] Executing script: /*** FosCam9828 Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.436] [I] [core] Loading module VistaCam from modules/VistaCam
[2017-01-01 23:01:04.479] [I] [core] Executing script: /*** VistaCam Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.481] [I] [core] Loading module SwitchPolling from modules/SwitchPolling
[2017-01-01 23:01:04.506] [I] [core] Executing script: /*** SwitchPolling Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.507] [I] [core] Loading module Camera from modules/Camera
[2017-01-01 23:01:04.553] [I] [core] Executing script: /*** Camera Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.555] [I] [core] Loading module DummyDevice from modules/DummyDevice
[2017-01-01 23:01:04.564] [I] [core] Executing script: /*** DummyDevice Z-Way HA module ******************************************* ...
[2017-01-01 23:01:04.566] [I] [core] Loading module SmartLight from modules/SmartLight
[2017-01-01 23:01:04.605] [I] [core] Executing script: /*** SmartLight Z-Way Home Automation module ************************************* ...
[2017-01-01 23:01:04.606] [I] [core] Starting automation...
[2017-01-01 23:01:04.640] [I] [core] Starting storage...
[2017-01-01 23:01:04.643] [I] [core] ZWay Automation started
[2017-01-01 23:01:12.671] [I] [core] Restarting Z-Wave binding
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data = Empty
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.nodeId = Empty
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.nodeId = 0 (0x00000000)
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.homeId = Empty
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.homeId = 0 (0x00000000)
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.SUCNodeId = Empty
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.SUCNodeId = 0 (0x00000000)
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.isPrimary = Empty
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.isPrimary = False
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.isInOthersNetwork = Empty
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.isInOthersNetwork = False
[2017-01-01 23:01:12.742] [D] [zway] SETDATA controller.data.isRealPrimary = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.isRealPrimary = False
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.isSUC = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.isSUC = False
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.SISPresent = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.SISPresent = False
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.libType = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.libType = ""
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.SDK = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.SDK = ""
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWlibMajor = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWlibMajor = 0 (0x00000000)
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWlibMinor = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWlibMinor = 0 (0x00000000)
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWLib = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWLib = 0 (0x00000000)
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWVersion = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWVersion = 0 (0x00000000)
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWaveChip = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.ZWaveChip = ""
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.APIVersion = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.APIVersion = ""
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.manufacturerId = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.manufacturerId = 0 (0x00000000)
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.vendor = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.vendor = ""
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.manufacturerProductType = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.manufacturerProductType = 0 (0x00000000)
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.manufacturerProductId = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.manufacturerProductId = 0 (0x00000000)
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.bootloaderCRC = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.firmwareCRC = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.capabilities = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.controllerState = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.nonManagmentJobs = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.nonManagmentJobs = 0 (0x00000000)
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.lastIncludedDevice = Empty
[2017-01-01 23:01:12.743] [D] [zway] SETDATA controller.data.lastIncludedDevice = 0 (0x00000000)
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.lastExcludedDevice = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.lastExcludedDevice = 0 (0x00000000)
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.secureInclusion = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.secureInclusion = True
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 0 (0x00000000)
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 0 (0x00000000)
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = 0 (0x00000000)
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = 0 (0x00000000)
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.countJobs = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.countJobs = False
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.memoryGetAddress = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.memoryGetData = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.functionClasses = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.functionClassesNames = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.softwareRevisionVersion = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.softwareRevisionVersion = "v2.2.5"
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.softwareRevisionId = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.softwareRevisionId = "d58b7617c4f553beca4c6f41705b48fa5a4cf56c"
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.softwareRevisionDate = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.softwareRevisionDate = "2016-11-15 11:42:15 +0300"
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.uuid = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.caps = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.frequency = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.frequency = ""
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.deviceRelaxDelay = Empty
[2017-01-01 23:01:12.744] [D] [zway] SETDATA controller.data.deviceRelaxDelay = 5 (0x00000005)
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.incomingPacket = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.incomingPacket.nodeId = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.incomingPacket.RSSI = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.incomingPacket.frameType = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.delivered = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.deliveryTime = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.packetLength = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.nodeId = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.returnRSSI = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.hops = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.returnChannel = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.txChannel = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.speed = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.schemeState = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.tries = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.outgoingPacket.lastFailPath = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = 127 (0x0000007f)
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = 127 (0x0000007f)
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = 127 (0x0000007f)
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = 0 (0x00000000)
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = 0 (0x00000000)
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = 0 (0x00000000)
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = 0 (0x00000000)
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = Empty
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = 0 (0x00000000)
[2017-01-01 23:01:12.745] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = Empty
[2017-01-01 23:01:12.746] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = 0 (0x00000000)
[2017-01-01 23:01:12.746] [D] [zway] SETDATA controller.data.statistics.priorityRoute = Empty
[2017-01-01 23:01:12.746] [D] [zway] SETDATA controller.data.statistics.priorityRoute.dstNodeId = Empty
[2017-01-01 23:01:12.746] [D] [zway] SETDATA controller.data.statistics.priorityRoute.routeType = Empty
[2017-01-01 23:01:12.746] [D] [zway] SETDATA controller.data.statistics.priorityRoute.speed = Empty
[2017-01-01 23:01:12.746] [D] [zway] SETDATA controller.data.statistics.priorityRoute.hops = Empty
[2017-01-01 23:01:12.746] [D] [zway] SETDATA controller.data.functionClasses = int[62]
[2017-01-01 23:01:12.746] [D] [zway] [00] 2
[2017-01-01 23:01:12.746] [D] [zway] [01] 3
[2017-01-01 23:01:12.746] [D] [zway] [02] 4
[2017-01-01 23:01:12.746] [D] [zway] [03] 5
[2017-01-01 23:01:12.746] [D] [zway] [04] 6
[2017-01-01 23:01:12.746] [D] [zway] [05] 7
[2017-01-01 23:01:12.746] [D] [zway] [06] 8
[2017-01-01 23:01:12.746] [D] [zway] [07] 11
[2017-01-01 23:01:12.746] [D] [zway] [08] 18
[2017-01-01 23:01:12.746] [D] [zway] [09] 19
[2017-01-01 23:01:12.746] [D] [zway] [10] 21
[2017-01-01 23:01:12.746] [D] [zway] [11] 22
[2017-01-01 23:01:12.746] [D] [zway] [12] 23
[2017-01-01 23:01:12.746] [D] [zway] [13] 32
[2017-01-01 23:01:12.746] [D] [zway] [14] 33
[2017-01-01 23:01:12.746] [D] [zway] [15] 34
[2017-01-01 23:01:12.746] [D] [zway] [16] 35
[2017-01-01 23:01:12.746] [D] [zway] [17] 36
[2017-01-01 23:01:12.746] [D] [zway] [18] 39
[2017-01-01 23:01:12.746] [D] [zway] [19] 41
[2017-01-01 23:01:12.746] [D] [zway] [20] 42
[2017-01-01 23:01:12.746] [D] [zway] [21] 43
[2017-01-01 23:01:12.746] [D] [zway] [22] 44
[2017-01-01 23:01:12.746] [D] [zway] [23] 45
[2017-01-01 23:01:12.746] [D] [zway] [24] 57
[2017-01-01 23:01:12.746] [D] [zway] [25] 58
[2017-01-01 23:01:12.746] [D] [zway] [26] 59
[2017-01-01 23:01:12.747] [D] [zway] [27] 65
[2017-01-01 23:01:12.747] [D] [zway] [28] 66
[2017-01-01 23:01:12.747] [D] [zway] [29] 68
[2017-01-01 23:01:12.747] [D] [zway] [30] 70
[2017-01-01 23:01:12.747] [D] [zway] [31] 71
[2017-01-01 23:01:12.747] [D] [zway] [32] 72
[2017-01-01 23:01:12.747] [D] [zway] [33] 73
[2017-01-01 23:01:12.747] [D] [zway] [34] 74
[2017-01-01 23:01:12.747] [D] [zway] [35] 75
[2017-01-01 23:01:12.747] [D] [zway] [36] 76
[2017-01-01 23:01:12.747] [D] [zway] [37] 77
[2017-01-01 23:01:12.747] [D] [zway] [38] 80
[2017-01-01 23:01:12.747] [D] [zway] [39] 81
[2017-01-01 23:01:12.747] [D] [zway] [40] 82
[2017-01-01 23:01:12.747] [D] [zway] [41] 83
[2017-01-01 23:01:12.747] [D] [zway] [42] 84
[2017-01-01 23:01:12.747] [D] [zway] [43] 85
[2017-01-01 23:01:12.747] [D] [zway] [44] 86
[2017-01-01 23:01:12.747] [D] [zway] [45] 87
[2017-01-01 23:01:12.747] [D] [zway] [46] 94
[2017-01-01 23:01:12.747] [D] [zway] [47] 96
[2017-01-01 23:01:12.747] [D] [zway] [48] 97
[2017-01-01 23:01:12.747] [D] [zway] [49] 98
[2017-01-01 23:01:12.747] [D] [zway] [50] 99
[2017-01-01 23:01:12.747] [D] [zway] [51] 128
[2017-01-01 23:01:12.747] [D] [zway] [52] 146
[2017-01-01 23:01:12.747] [D] [zway] [53] 147
[2017-01-01 23:01:12.747] [D] [zway] [54] 186
[2017-01-01 23:01:12.747] [D] [zway] [55] 190
[2017-01-01 23:01:12.747] [D] [zway] [56] 210
[2017-01-01 23:01:12.747] [D] [zway] [57] 211
[2017-01-01 23:01:12.747] [D] [zway] [58] 242
[2017-01-01 23:01:12.747] [D] [zway] [59] 243
[2017-01-01 23:01:12.747] [D] [zway] [60] 244
[2017-01-01 23:01:12.747] [D] [zway] [61] 245
[2017-01-01 23:01:12.747] [D] [zway] SETDATA controller.data.functionClassesNames = string[62]
[2017-01-01 23:01:12.747] [D] [zway] [00] "SerialAPIGetInitData"
[2017-01-01 23:01:12.747] [D] [zway] [01] "SerialAPIApplicationNodeInformation"
[2017-01-01 23:01:12.747] [D] [zway] [02] "ApplicationCommandHandler"
[2017-01-01 23:01:12.747] [D] [zway] [03] "GetControllerCapabilities"
[2017-01-01 23:01:12.747] [D] [zway] [04] "SerialAPISetTimeouts"
[2017-01-01 23:01:12.747] [D] [zway] [05] "GetSerialAPICapabilities"
[2017-01-01 23:01:12.748] [D] [zway] [06] "SerialAPISoftReset"
[2017-01-01 23:01:12.748] [D] [zway] [07] "SerialAPISetup"
[2017-01-01 23:01:12.748] [D] [zway] [08] "SendNodeInformation"
[2017-01-01 23:01:12.748] [D] [zway] [09] "SendData"
[2017-01-01 23:01:12.748] [D] [zway] [10] "GetVersion"
[2017-01-01 23:01:12.748] [D] [zway] [11] "SendDataAbort"
[2017-01-01 23:01:12.748] [D] [zway] [12] "RFPowerLevelSet"
[2017-01-01 23:01:12.748] [D] [zway] [13] "GetHomeId"
[2017-01-01 23:01:12.748] [D] [zway] [14] "MemoryGetByte"
[2017-01-01 23:01:12.748] [D] [zway] [15] "MemoryPutByte"
[2017-01-01 23:01:12.748] [D] [zway] [16] "MemoryGetBuffer"
[2017-01-01 23:01:12.748] [D] [zway] [17] "MemoryPutBuffer"
[2017-01-01 23:01:12.748] [D] [zway] [18] "FlashAutoProgSet"
[2017-01-01 23:01:12.748] [D] [zway] [19] "NVMGetId"
[2017-01-01 23:01:12.748] [D] [zway] [20] "NVMExtReadLongBuffer"
[2017-01-01 23:01:12.748] [D] [zway] [21] "NVMExtWriteLongBuffer"
[2017-01-01 23:01:12.748] [D] [zway] [22] "NVMExtReadLongByte"
[2017-01-01 23:01:12.748] [D] [zway] [23] "NVMExtWriteLongByte"
[2017-01-01 23:01:12.748] [D] [zway] [24] "ClearNetworkStats"
[2017-01-01 23:01:12.748] [D] [zway] [25] "GetNetworkStats"
[2017-01-01 23:01:12.748] [D] [zway] [26] "GetBackgroundRSSI"
[2017-01-01 23:01:12.748] [D] [zway] [27] "GetNodeProtocolInformation"
[2017-01-01 23:01:12.748] [D] [zway] [28] "SetDefault"
[2017-01-01 23:01:12.748] [D] [zway] [29] "ReplicationReceiveComplete"
[2017-01-01 23:01:12.748] [D] [zway] [30] "AssignReturnRoute"
[2017-01-01 23:01:12.748] [D] [zway] [31] "DeleteReturnRoute"
[2017-01-01 23:01:12.748] [D] [zway] [32] "RequestNodeNeighbourUpdate"
[2017-01-01 23:01:12.748] [D] [zway] [33] "ApplicationNodeUpdate"
[2017-01-01 23:01:12.748] [D] [zway] [34] "AddNodeToNetwork"
[2017-01-01 23:01:12.748] [D] [zway] [35] "RemoveNodeFromNetwork"
[2017-01-01 23:01:12.748] [D] [zway] [36] "CreateNewPrimary"
[2017-01-01 23:01:12.748] [D] [zway] [37] "ControllerChange"
[2017-01-01 23:01:12.748] [D] [zway] [38] "SetLearnMode"
[2017-01-01 23:01:12.748] [D] [zway] [39] "AssignSUCReturnRoute"
[2017-01-01 23:01:12.748] [D] [zway] [40] "EnableSUC"
[2017-01-01 23:01:12.748] [D] [zway] [41] "RequestNetworkUpdate"
[2017-01-01 23:01:12.748] [D] [zway] [42] "SetSUCNodeId"
[2017-01-01 23:01:12.748] [D] [zway] [43] "DeleteSUCReturnRoute"
[2017-01-01 23:01:12.748] [D] [zway] [44] "GetSUCNodeId"
[2017-01-01 23:01:12.748] [D] [zway] [45] "SendSUCNodeId"
[2017-01-01 23:01:12.748] [D] [zway] [46] "ExploreRequestInclusion"
[2017-01-01 23:01:12.748] [D] [zway] [47] "RequestNodeInformation"
[2017-01-01 23:01:12.748] [D] [zway] [48] "RemoveFailedNode"
[2017-01-01 23:01:12.748] [D] [zway] [49] "IsFailedNode"
[2017-01-01 23:01:12.748] [D] [zway] [50] "ReplaceFailedNode"
[2017-01-01 23:01:12.748] [D] [zway] [51] "GetRoutingTableLine"
[2017-01-01 23:01:12.748] [D] [zway] [52] "GetPriorityRoute"
[2017-01-01 23:01:12.748] [D] [zway] [53] "SetPriorityRoute"
[2017-01-01 23:01:12.749] [D] [zway] [54] "RFPowerLevelGet"
[2017-01-01 23:01:12.749] [D] [zway] [55] "SendTestFrame"
[2017-01-01 23:01:12.749] [D] [zway] [56] "WatchDogStart"
[2017-01-01 23:01:12.749] [D] [zway] [57] "WatchDogStop"
[2017-01-01 23:01:12.749] [D] [zway] [58] "ZMEFreqChange"
[2017-01-01 23:01:12.749] [D] [zway] [59] "ZMERestore"
[2017-01-01 23:01:12.749] [D] [zway] [60] "ZMEBootloaderFlash"
[2017-01-01 23:01:12.749] [D] [zway] [61] "ZMECapabilities"
[2017-01-01 23:01:12.749] [D] [zway] Opened device: /dev/ttyAMA0
[2017-01-01 23:01:12.749] [D] [zway] Worker thread successfully created
[2017-01-01 23:01:12.749] [D] [zway] Worker thread entry point
[2017-01-01 23:01:12.750] [I] [zway] Adding job: Get controller info and supported function classes
[2017-01-01 23:01:12.759] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-01-01 23:01:12.965] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-01-01 23:01:12.965] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-01-01 23:01:13.168] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-01-01 23:01:13.168] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-01-01 23:01:13.372] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2017-01-01 23:01:13.373] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2017-01-01 23:01:13.373] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2017-01-01 23:01:13.373] [C] [zway] Get Serial API Capabilities returned zero.
[2017-01-01 23:01:13.373] [I] [zway] Removing job: Get controller info and supported function classes
[2017-01-01 23:01:13.383] [D] [zway] Worker thread exit point
[2017-01-01 23:01:13.383] [D] [zway] Worker thread successfully finished
[2017-01-01 23:01:13.384] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2017-01-01 23:01:13.411] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed