forum

Hi everybody,

i just (23.03) bought a PI3 and the Razberry daughterboard,
but they will not work together.

I read the forum about pi3 problems up and down, but no solution works for me.

Here is my actual configuration:

1. new formated SD
2. install Noobs 1.9.0 from raspberrypi.org
3. install zwave with wget -q -O - http://razberry.z-wave.me ...

there are two modes of incorrect operation:

1. SmartHome UI - Error / Unable to load data

2. if not (1) then, the inclusion failed
with the error code "inclusion takes a long time ..."
and no device will be detected
and the red led lights permanently.

see attachment files

Is there a damage on the razberry daughterboard or is my setup incorrect ?



Attachment:

cmdline.txt:
dwc_otg.lpm_enable=0 console=tty1 root=/dev/mmcblk0p7 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait

config.txt
dtoverlay=pi3-disable-bt

z-way-server log
[2016-04-21 16:05:01.688] [D] [zway] Opened device: /dev/ttyAMA0
[2016-04-21 16:05:01.688] [D] [zway] Worker thread successfully created
[2016-04-21 16:05:01.688] [D] [zway] Worker thread entry point
[2016-04-21 16:05:01.689] [zway] Adding job: Get controller info and supported function classes
[2016-04-21 16:05:01.698] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2016-04-21 16:05:01.699] [D] [zway] RECEIVED CAN
[2016-04-21 16:05:02.002] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2016-04-21 16:05:02.002] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2016-04-21 16:05:02.003] [D] [zway] RECEIVED CAN
[2016-04-21 16:05:02.305] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2016-04-21 16:05:02.305] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2016-04-21 16:05:02.306] [D] [zway] RECEIVED CAN
[2016-04-21 16:05:02.608] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2016-04-21 16:05:02.608] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2016-04-21 16:05:02.608] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2016-04-21 16:05:02.609] [C] [zway] Get Serial API Capabilities returned zero.
[2016-04-21 16:05:02.609] [zway] Removing job: Get controller info and supported function classes
[2016-04-21 16:05:02.619] [D] [zway] Worker thread exit point
[2016-04-21 16:05:02.619] [D] [zway] Worker thread successfully finished
[2016-04-21 16:05:02.619] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2016-04-21 16:05:02.637] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Hi MiHerrmann ; They do work together, as that's what I am running now. In Raspi-config, did you disable the serial console?

Hi evansnp,

no i didn't has disabled the serial console. Ok ... then to raspi-config, disable serial console ... clear logfile ... shutdown pi3 ... disconnect from powersupply for 10 seconds ... reboot.

The result ist the same:

[2016-04-23 08:27:16.571] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Any more ideas ?

Regards Michael




Logfile:

[2016-04-23 08:27:03.394] [core] Executing script: /*** Z-Way Home Automation Engine main executable ***************************** ...
[2016-04-23 08:27:03.401] [core] Executing script: // Comon utilities and functions ...
[2016-04-23 08:27:03.415] [core] Executing script: // This script transforms old formats to new ...
[2016-04-23 08:27:03.501] [core] Executing script: /*! ...
[2016-04-23 08:27:03.552] [core] Executing script: // Underscore.js 1.7.0 ...
[2016-04-23 08:27:03.586] [core] Executing script: /*! ...
[2016-04-23 08:27:03.608] [core] Executing script: /*** Z-Way HA Virtual Device base class *************************************** ...
[2016-04-23 08:27:03.614] [core] Executing script: /*** Z-Way DevicesCollection class ************************************ ...
[2016-04-23 08:27:03.644] [core] Executing script: /*** Z-Way HA Controller class module ***************************************** ...
[2016-04-23 08:27:03.669] [I] [core] Executing script: /*** Automation Webserver Auth Controller ************************************* ...
[2016-04-23 08:27:03.675] [I] [core] Executing script: /*** Z-Way HA Automation module base class ************************************ ...
[2016-04-23 08:27:03.681] [I] [core] Executing script: ...
[2016-04-23 08:27:03.694] [I] [core] Executing script: /*** Main Automation storage module ***************************************** ...
[2016-04-23 08:27:03.717] [I] [core] Executing script: /*** Main Automation webserver module ***************************************** ...
[2016-04-23 08:27:03.721] [I] [core] Executing script: // Make this testable by pulling in underscore from the parent module ...
[2016-04-23 08:27:03.732] [I] [core] Executing script: /*** Main Automation storage module ***************************************** ...
[2016-04-23 08:27:03.741] [I] [core] --- Loading ZAutomation classes
[2016-04-23 08:27:04.515] [I] [core] Starting ZWay Automation webserver
[2016-04-23 08:27:04.519] [I] [core] Loading modules...
[2016-04-23 08:27:04.527] [I] [core] Loading module ZWave from modules/ZWave
[2016-04-23 08:27:04.545] [I] [core] Executing script: /*** Z-Wave Binding module ******************************************************** ...
[2016-04-23 08:27:04.557] [I] [core] Instantiating module 1 from class ZWave
[2016-04-23 08:27:04.558] [I] [core] --- Starting module Z-Wave Network Access
[2016-04-23 08:27:04.685] [D] [zway] SETDATA controller.data = Empty
[2016-04-23 08:27:04.685] [D] [zway] SETDATA controller.data.nodeId = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.homeId = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.SUCNodeId = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.isPrimary = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.isInOthersNetwork = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.isRealPrimary = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.isSUC = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.SISPresent = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.libType = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.SDK = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.ZWlibMajor = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.ZWlibMinor = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.ZWLib = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.ZWVersion = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.ZWaveChip = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.APIVersion = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.manufacturerId = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.vendor = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.manufacturerProductType = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.manufacturerProductId = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.capabilities = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.controllerState = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.nonManagmentJobs = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.lastIncludedDevice = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.lastExcludedDevice = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.secureInclusion = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.countJobs = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.memoryGetAddress = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.memoryGetData = Empty
[2016-04-23 08:27:04.686] [D] [zway] SETDATA controller.data.functionClasses = Empty
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.functionClassesNames = Empty
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.softwareRevisionVersion = Empty
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.softwareRevisionId = Empty
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.softwareRevisionDate = Empty
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.uuid = Empty
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.caps = Empty
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.frequency = Empty
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.nodeId = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.homeId = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.SUCNodeId = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.isPrimary = False
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.isInOthersNetwork = False
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.isRealPrimary = False
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.isSUC = False
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.SISPresent = False
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.ZWLib = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.ZWlibMajor = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.ZWlibMinor = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.ZWVersion = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.manufacturerId = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.manufacturerProductType = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.manufacturerProductId = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.nonManagmentJobs = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.secureInclusion = True
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = 0 (0x00000000)
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.countJobs = False
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.softwareRevisionVersion = "v2.2.2"
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.softwareRevisionId = "d2212e41b6487bf6efe6df730db05571edc64915"
[2016-04-23 08:27:04.687] [D] [zway] SETDATA controller.data.softwareRevisionDate = "2016-04-11 11:00:34 +0300"
[2016-04-23 08:27:04.688] [D] [zway] SETDATA controller.data.functionClasses = int[56]
[2016-04-23 08:27:04.688] [D] [zway] [00] 2
[2016-04-23 08:27:04.688] [D] [zway] [01] 3
[2016-04-23 08:27:04.688] [D] [zway] [02] 4
[2016-04-23 08:27:04.688] [D] [zway] [03] 5
[2016-04-23 08:27:04.688] [D] [zway] [04] 6
[2016-04-23 08:27:04.688] [D] [zway] [05] 7
[2016-04-23 08:27:04.688] [D] [zway] [06] 8
[2016-04-23 08:27:04.688] [D] [zway] [07] 18
[2016-04-23 08:27:04.688] [D] [zway] [08] 19
[2016-04-23 08:27:04.688] [D] [zway] [09] 21
[2016-04-23 08:27:04.688] [D] [zway] [10] 22
[2016-04-23 08:27:04.688] [D] [zway] [11] 23
[2016-04-23 08:27:04.688] [D] [zway] [12] 32
[2016-04-23 08:27:04.688] [D] [zway] [13] 33
[2016-04-23 08:27:04.688] [D] [zway] [14] 34
[2016-04-23 08:27:04.688] [D] [zway] [15] 35
[2016-04-23 08:27:04.688] [D] [zway] [16] 36
[2016-04-23 08:27:04.688] [D] [zway] [17] 39
[2016-04-23 08:27:04.688] [D] [zway] [18] 41
[2016-04-23 08:27:04.688] [D] [zway] [19] 42
[2016-04-23 08:27:04.688] [D] [zway] [20] 43
[2016-04-23 08:27:04.688] [D] [zway] [21] 44
[2016-04-23 08:27:04.688] [D] [zway] [22] 45
[2016-04-23 08:27:04.688] [D] [zway] [23] 65
[2016-04-23 08:27:04.688] [D] [zway] [24] 66
[2016-04-23 08:27:04.688] [D] [zway] [25] 68
[2016-04-23 08:27:04.688] [D] [zway] [26] 70
[2016-04-23 08:27:04.688] [D] [zway] [27] 71
[2016-04-23 08:27:04.688] [D] [zway] [28] 72
[2016-04-23 08:27:04.688] [D] [zway] [29] 73
[2016-04-23 08:27:04.688] [D] [zway] [30] 74
[2016-04-23 08:27:04.688] [D] [zway] [31] 75
[2016-04-23
08:27:04.688] [D] [zway] [32] 76
[2016-04-23 08:27:04.688] [D] [zway] [33] 77
[2016-04-23 08:27:04.688] [D] [zway] [34] 80
[2016-04-23 08:27:04.688] [D] [zway] [35] 81
[2016-04-23 08:27:04.688] [D] [zway] [36] 82
[2016-04-23 08:27:04.689] [D] [zway] [37] 83
[2016-04-23 08:27:04.689] [D] [zway] [38] 84
[2016-04-23 08:27:04.689] [D] [zway] [39] 85
[2016-04-23 08:27:04.689] [D] [zway] [40] 86
[2016-04-23 08:27:04.689] [D] [zway] [41] 87
[2016-04-23 08:27:04.689] [D] [zway] [42] 94
[2016-04-23 08:27:04.689] [D] [zway] [43] 96
[2016-04-23 08:27:04.689] [D] [zway] [44] 97
[2016-04-23 08:27:04.689] [D] [zway] [45] 98
[2016-04-23 08:27:04.689] [D] [zway] [46] 99
[2016-04-23 08:27:04.689] [D] [zway] [47] 128
[2016-04-23 08:27:04.689] [D] [zway] [48] 186
[2016-04-23 08:27:04.689] [D] [zway] [49] 190
[2016-04-23 08:27:04.689] [D] [zway] [50] 210
[2016-04-23 08:27:04.689] [D] [zway] [51] 211
[2016-04-23 08:27:04.689] [D] [zway] [52] 242
[2016-04-23 08:27:04.689] [D] [zway] [53] 243
[2016-04-23 08:27:04.689] [D] [zway] [54] 244
[2016-04-23 08:27:04.689] [D] [zway] [55] 245
[2016-04-23 08:27:04.689] [D] [zway] SETDATA controller.data.functionClassesNames = string[56]
[2016-04-23 08:27:04.689] [D] [zway] [00] "SerialAPIGetInitData"
[2016-04-23 08:27:04.689] [D] [zway] [01] "SerialAPIApplicationNodeInformation"
[2016-04-23 08:27:04.689] [D] [zway] [02] "ApplicationCommandHandler"
[2016-04-23 08:27:04.689] [D] [zway] [03] "GetControllerCapabilities"
[2016-04-23 08:27:04.689] [D] [zway] [04] "SerialAPISetTimeouts"
[2016-04-23 08:27:04.689] [D] [zway] [05] "GetSerialAPICapabilities"
[2016-04-23 08:27:04.689] [D] [zway] [06] "SerialAPISoftReset"
[2016-04-23 08:27:04.689] [D] [zway] [07] "SendNodeInformation"
[2016-04-23 08:27:04.689] [D] [zway] [08] "SendData"
[2016-04-23 08:27:04.689] [D] [zway] [09] "GetVersion"
[2016-04-23 08:27:04.689] [D] [zway] [10] "SendDataAbort"
[2016-04-23 08:27:04.689] [D] [zway] [11] "RFPowerLevelSet"
[2016-04-23 08:27:04.689] [D] [zway] [12] "GetHomeId"
[2016-04-23 08:27:04.689] [D] [zway] [13] "MemoryGetByte"
[2016-04-23 08:27:04.689] [D] [zway] [14] "MemoryPutByte"
[2016-04-23 08:27:04.689] [D] [zway] [15] "MemoryGetBuffer"
[2016-04-23 08:27:04.689] [D] [zway] [16] "MemoryPutBuffer"
[2016-04-23 08:27:04.689] [D] [zway] [17] "FlashAutoProgSet"
[2016-04-23 08:27:04.689] [D] [zway] [18] "NVMGetId"
[2016-04-23 08:27:04.689] [D] [zway] [19] "NVMExtReadLongBuffer"
[2016-04-23 08:27:04.689] [D] [zway] [20] "NVMExtWriteLongBuffer"
[2016-04-23 08:27:04.690] [D] [zway] [21] "NVMExtReadLongByte"
[2016-04-23 08:27:04.690] [D] [zway] [22] "NVMExtWriteLongByte"
[2016-04-23 08:27:04.690] [D] [zway] [23] "GetNodeProtocolInformation"
[2016-04-23 08:27:04.690] [D] [zway] [24] "SetDefault"
[2016-04-23 08:27:04.690] [D] [zway] [25] "ReplicationReceiveComplete"
[2016-04-23 08:27:04.690] [D] [zway] [26] "AssignReturnRoute"
[2016-04-23 08:27:04.690] [D] [zway] [27] "DeleteReturnRoute"
[2016-04-23 08:27:04.690] [D] [zway] [28] "RequestNodeNeighbourUpdate"
[2016-04-23 08:27:04.690] [D] [zway] [29] "ApplicationNodeUpdate"
[2016-04-23 08:27:04.690] [D] [zway] [30] "AddNodeToNetwork"
[2016-04-23 08:27:04.690] [D] [zway] [31] "RemoveNodeFromNetwork"
[2016-04-23 08:27:04.690] [D] [zway] [32] "CreateNewPrimary"
[2016-04-23 08:27:04.690] [D] [zway] [33] "ControllerChange"
[2016-04-23 08:27:04.690] [D] [zway] [34] "SetLearnMode"
[2016-04-23 08:27:04.690] [D] [zway] [35] "AssignSUCReturnRoute"
[2016-04-23 08:27:04.690] [D] [zway] [36] "EnableSUC"
[2016-04-23 08:27:04.690] [D] [zway] [37] "RequestNetworkUpdate"
[2016-04-23 08:27:04.690] [D] [zway] [38] "SetSUCNodeId"
[2016-04-23 08:27:04.690] [D] [zway] [39] "DeleteSUCReturnRoute"
[2016-04-23 08:27:04.690] [D] [zway] [40] "GetSUCNodeId"
[2016-04-23 08:27:04.690] [D] [zway] [41] "SendSUCNodeId"
[2016-04-23 08:27:04.690] [D] [zway] [42] "ExploreRequestInclusion"
[2016-04-23 08:27:04.690] [D] [zway] [43] "RequestNodeInformation"
[2016-04-23 08:27:04.690] [D] [zway] [44] "RemoveFailedNode"
[2016-04-23 08:27:04.690] [D] [zway] [45] "IsFailedNode"
[2016-04-23 08:27:04.690] [D] [zway] [46] "ReplaceFailedNode"
[2016-04-23 08:27:04.690] [D] [zway] [47] "GetRoutingTableLine"
[2016-04-23 08:27:04.690] [D] [zway] [48] "RFPowerLevelGet"
[2016-04-23 08:27:04.690] [D] [zway] [49] "SendTestFrame"
[2016-04-23 08:27:04.690] [D] [zway] [50] "WatchDogStart"
[2016-04-23 08:27:04.690] [D] [zway] [51] "WatchDogStop"
[2016-04-23 08:27:04.690] [D] [zway] [52] "ZMEFreqChange"
[2016-04-23 08:27:04.690] [D] [zway] [53] "ZMERestore"
[2016-04-23 08:27:04.690] [D] [zway] [54] "ZMEBootloaderFlash"
[2016-04-23 08:27:04.690] [D] [zway] [55] "ZMECapabilities"
[2016-04-23 08:27:04.690] [D] [zway] Opened device: /dev/ttyAMA0
[2016-04-23 08:27:04.691] [D] [zway] Worker thread successfully created
[2016-04-23 08:27:04.691] [D] [zway] Worker thread entry point
[2016-04-23 08:27:04.693] [I] [zway] Adding job: Get controller info and supported function classes
[2016-04-23 08:27:04.706] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2016-04-23 08:27:04.708] [E] [zway] RECEIVED UNKNOWN PACKET TYPE: 0x2b
[2016-04-23 08:27:04.708] [W] [zway] Received SOF, while awaiting ACK
[2016-04-23 08:27:05.016] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2016-04-23 08:27:05.016] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2016-04-23 08:27:05.229] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2016-04-23 08:27:05.229] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2016-04-23 08:27:05.441] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2016-04-23 08:27:05.441] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2016-04-23 08:27:05.441] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2016-04-23 08:27:05.441] [C] [zway] Get Serial API Capabilities returned zero.
[2016-04-23 08:27:05.441] [I] [zway] Removing job: Get controller info and supported function classes
[2016-04-23 08:27:05.451] [D] [zway] Worker thread exit point
[2016-04-23 08:27:05.452] [D] [zway] Worker thread successfully finished
[2016-04-23 08:27:05.453] [I] [core] Terminating Z-Wave binding
[2016-04-23 08:27:05.458] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2016-04-23 08:27:05.488] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed
[2016-04-23 08:27:05.490] [I] [core] Loading module Cron from modules/Cron
[2016-04-23 08:27:05.493] [I] [core] Executing script: /*** Cron ZAutomation module ************************************************** ...
[2016-04-23 08:27:05.500] [I] [core] Instantiating module 2 from class Cron
[2016-04-23 08:27:05.501] [I] [core] --- Starting module System Clock (CRON)
[2016-04-23 08:27:05.506] [I] [core] Loading module Camera from modules/Camera
[2016-04-23 08:27:05.516] [I] [core] Executing script: /*** Camera Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.519] [I] [core] Loading module Sonos from modules/Sonos
[2016-04-23 08:27:05.524] [I] [core] Executing script: /*** Sonos Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.525] [I] [core] Loading module Notification from modules/Notification
[2016-04-23 08:27:05.527] [I] [core] Executing script: /*** Notification Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.528] [I] [core] Loading module CustomUserCodeLoader from modules/CustomUserCodeLoader
[2016-04-23 08:27:05.529] [I] [core] Executing script: /*** CustomUserCodeLoader ZAutomation module **************************************** ...
[2016-04-23 08:27:05.531] [I] [core] Loading module ThermostatDevice from modules/ThermostatDevice
[2016-04-23 08:27:05.533] [I] [core] Executing script: /*** ThermostatDevice Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.536] [I] [core] Loading module SensorsPollingLogging from modules/SensorsPollingLogging
[2016-04-23 08:27:05.539] [I] [core] Executing script: /*** SensorsPollingLogging Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.540] [I] [core] Loading module FosCam9805 from modules/FosCam9805
[2016-04-23 08:27:05.542] [I] [core] Executing script: /*** FosCam9805 Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.544] [I] [core] Loading module DeviceHistory from modules/DeviceHistory
[2016-04-23 08:27:05.548] [I] [core] Executing script: /*** DeviceHistory Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.550] [I] [core] Loading module LogicalRules from modules/LogicalRules
[2016-04-23 08:27:05.554] [I] [core] Executing script: /*** LogicalRules Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.555] [I] [core] Loading module OpenRemoteHelpers from modules/OpenRemoteHelpers
[2016-04-23 08:27:05.558] [I] [core] Executing script: /* RaZOR ...
[2016-04-23 08:27:05.558] [I] [core] Loading module HTTPDevice from modules/HTTPDevice
[2016-04-23 08:27:05.564] [I] [core] Executing script: /*** HTTPDevice Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.565] [I] [core] Loading module InfoWidget from modules/InfoWidget
[2016-04-23 08:27:05.576] [I] [core] Executing script: /*** InfoWidget Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.583] [I] [core] Instantiating module 5 from class InfoWidget
[2016-04-23 08:27:05.584] [I] [core] --- Starting module Information
Widget
[2016-04-23 08:27:05.589] [I] [core] Creating device text InfoWidget_5_Int
[2016-04-23 08:27:05.609] [I] [core] Loading module FosCam9828 from modules/FosCam9828
[2016-04-23 08:27:05.612] [I] [core] Executing script: /*** FosCam9828 Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.613] [I] [core] Loading module SwitchControlGenerator from modules/SwitchControlGenerator
[2016-04-23 08:27:05.618] [I] [core] Executing script: /*** SwitchControlGenerator Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.629] [I] [core] Instantiating module 8 from class SwitchControlGenerator
[2016-04-23 08:27:05.632] [I] [core] --- Starting module Trap events from Remotes and Sensors
[2016-04-23 08:27:05.637] [I] [core] Loading module GroupDevices from modules/GroupDevices
[2016-04-23 08:27:05.649] [I] [core] Executing script: /*** GroupDevices Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.650] [I] [core] Loading module OpenWeather from modules/OpenWeather
[2016-04-23 08:27:05.652] [I] [core] Executing script: /*** OpenWeather Extended Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.654] [I] [core] Loading module RoundRobinScenes from modules/RoundRobinScenes
[2016-04-23 08:27:05.655] [I] [core] Executing script: /*** RoundRobinScenes Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.656] [I] [core] Loading module SmartLight from modules/SmartLight
[2016-04-23 08:27:05.662] [I] [core] Executing script: /*** SmartLight Z-Way Home Automation module ************************************* ...
[2016-04-23 08:27:05.662] [I] [core] Loading module TagOnOff from modules/TagOnOff
[2016-04-23 08:27:05.667] [I] [core] Executing script: /*** TagOnOff Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.672] [I] [core] Loading module NotificationSMSru from modules/NotificationSMSru
[2016-04-23 08:27:05.673] [I] [core] Executing script: /*** NotificationSMSru Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.674] [I] [core] Loading module BatteryPolling from modules/BatteryPolling
[2016-04-23 08:27:05.685] [I] [core] Executing script: /*** BatteryPolling Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.697] [I] [core] Instantiating module 7 from class BatteryPolling
[2016-04-23 08:27:05.698] [I] [core] --- Starting module Battery Polling
[2016-04-23 08:27:05.700] [I] [core] Creating device battery BatteryPolling_7
[2016-04-23 08:27:05.710] [I] [core] Loading module ImportRemoteHA from modules/ImportRemoteHA
[2016-04-23 08:27:05.718] [I] [core] Executing script: /*** ImportRemoteHA Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.719] [I] [core] Loading module InbandNotifications from modules/InbandNotifications
[2016-04-23 08:27:05.722] [I] [core] Executing script: /*** InbandNotifications Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.731] [I] [core] Instantiating module 3 from class InbandNotifications
[2016-04-23 08:27:05.733] [I] [core] --- Starting module Inband Notifier
[2016-04-23 08:27:05.738] [I] [core] Loading module LightScene from modules/LightScene
[2016-04-23 08:27:05.746] [I] [core] Executing script: /*** LightScene Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.747] [I] [core] Loading module CustomUserCodeZWay from modules/CustomUserCodeZWay
[2016-04-23 08:27:05.750] [I] [core] Executing script: /*** CustomUserCodeZWay ZAutomation module **************************************** ...
[2016-04-23 08:27:05.751] [I] [core] Loading module SensorValueLogging from modules/SensorValueLogging
[2016-04-23 08:27:05.755] [I] [core] Executing script: /*** SensorValueLogging Z-Way Home Automation module ************************************* ...
[2016-04-23 08:27:05.757] [I] [core] Loading module FosCam9821 from modules/FosCam9821
[2016-04-23 08:27:05.763] [I] [core] Executing script: /*** FosCam9821 Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.764] [I] [core] Loading module CustomUserCode from modules/CustomUserCode
[2016-04-23 08:27:05.767] [I] [core] Executing script: /*** CustomUserCode ZAutomation module **************************************** ...
[2016-04-23 08:27:05.767] [I] [core] Loading module VistaCam from modules/VistaCam
[2016-04-23 08:27:05.770] [I] [core] Executing script: /*** VistaCam Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.771] [I] [core] Loading module SensorsPolling from modules/SensorsPolling
[2016-04-23 08:27:05.774] [I] [core] Executing script: /*** SensorsPolling Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.780] [I] [core] Instantiating module 6 from class SensorsPolling
[2016-04-23 08:27:05.783] [I] [core] --- Starting module Periodical Sensor Polling
[2016-04-23 08:27:05.786] [I] [core] Loading module EnOcean from modules/EnOcean
[2016-04-23 08:27:05.793] [I] [core] Executing script: /*** EnOcean Binding module ******************************************************** ...
[2016-04-23 08:27:05.795] [I] [core] Loading module AutoOff from modules/AutoOff
[2016-04-23 08:27:05.797] [I] [core] Executing script: /*** AutoOff Z-Way Home Automation module ************************************* ...
[2016-04-23 08:27:05.798] [I] [core] Loading module DelayedScene from modules/DelayedScene
[2016-04-23 08:27:05.800] [I] [core] Executing script: /*** DelayedScene Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.801] [I] [core] Loading module AutoLock from modules/AutoLock
[2016-04-23 08:27:05.802] [I] [core] Executing script: /*** AutoLock Z-Way Home Automation module ************************************* ...
[2016-04-23 08:27:05.803] [I] [core] Loading module RGB from modules/RGB
[2016-04-23 08:27:05.809] [I] [core] Executing script: /*** RGB Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.810] [I] [core] Loading module BindDevices from modules/BindDevices
[2016-04-23 08:27:05.815] [I] [core] Executing script: /*** BindDevices Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.817] [I] [core] Loading module SecurityMode from modules/SecurityMode
[2016-04-23 08:27:05.835] [I] [core] Executing script: /*** SecurityMode Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.836] [I] [core] Loading module DummyDevice from modules/DummyDevice
[2016-04-23 08:27:05.850] [I] [core] Executing script: /*** DummyDevice Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.851] [I] [core] Loading module SwitchPolling from modules/SwitchPolling
[2016-04-23 08:27:05.861] [I] [core] Executing script: /*** SwitchPolling Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.862] [I] [core] Loading module IfThen from modules/IfThen
[2016-04-23 08:27:05.867] [I] [core] Executing script: /*** IfThen Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.868] [I] [core] Loading module MultilineSensor from modules/MultilineSensor
[2016-04-23 08:27:05.880] [I] [core] Executing script: /*** MultilineSensor Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.881] [I] [core] Loading module PoppCam from modules/PoppCam
[2016-04-23 08:27:05.884] [I] [core] Executing script: /*** PoppCam Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.885] [I] [core] Loading module CodeDevice from modules/CodeDevice
[2016-04-23 08:27:05.894] [I] [core] Executing script: /*** CodeDevice Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.895] [I] [core] Loading module RemoteAccess from modules/RemoteAccess
[2016-04-23 08:27:05.903] [I] [core] Executing script: /*** RemoteAccess Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.909] [I] [core] Instantiating module 4 from class RemoteAccess
[2016-04-23 08:27:05.910] [I] [core] --- Starting module Remote Access
[2016-04-23 08:27:05.920] [I] [core] success! start zbw ...
[2016-04-23 08:27:05.922] [I] [core] Loading module FosCam9826 from modules/FosCam9826
[2016-04-23 08:27:05.930] [I] [core] Executing script: /*** FosCam9826 Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.932] [I] [core] Loading module ScheduledScene from modules/ScheduledScene
[2016-04-23 08:27:05.934] [I] [core] Executing script: /*** ScheduledScene Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.935] [I] [core] Loading module PhilioHW from modules/PhilioHW
[2016-04-23 08:27:05.941] [I] [core] Executing script: /*** PhilioHW Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.942] [I] [core] Loading module CounterTriggeringSensor from modules/CounterTriggeringSensor
[2016-04-23 08:27:05.946] [I] [core] Executing script: /*** Counter triggering binary sensor Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.947] [I] [core] Loading module GlobalCache from modules/GlobalCache
[2016-04-23 08:27:05.949] [I] [core] Executing script: /*** GlobalCache Z-Way HA module ******************************************* ...
[2016-04-23 08:27:05.950] [I] [core] Starting automation...
[2016-04-23 08:27:05.962] [I] [core] Starting storage...
[2016-04-23 08:27:05.963] [I] [core] ZWay Automation started
[2016-04-23 08:27:15.492] [I] [core] Restarting Z-Wave binding
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data = Empty
[2016-04-23 08:27:15.618] [D] [zway]
SETDATA controller.data.nodeId = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.homeId = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.SUCNodeId = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.isPrimary = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.isInOthersNetwork = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.isRealPrimary = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.isSUC = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.SISPresent = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.libType = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.SDK = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.ZWlibMajor = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.ZWlibMinor = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.ZWLib = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.ZWVersion = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.ZWaveChip = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.APIVersion = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.manufacturerId = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.vendor = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.manufacturerProductType = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.manufacturerProductId = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.capabilities = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.controllerState = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.nonManagmentJobs = Empty
[2016-04-23 08:27:15.618] [D] [zway] SETDATA controller.data.lastIncludedDevice = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.lastExcludedDevice = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.secureInclusion = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.countJobs = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.memoryGetAddress = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.memoryGetData = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.functionClasses = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.functionClassesNames = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.softwareRevisionVersion = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.softwareRevisionId = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.softwareRevisionDate = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.uuid = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.caps = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.frequency = Empty
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.nodeId = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.homeId = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.SUCNodeId = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.isPrimary = False
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.isInOthersNetwork = False
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.isRealPrimary = False
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.isSUC = False
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.SISPresent = False
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.ZWLib = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.ZWlibMajor = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.ZWlibMinor = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.ZWVersion = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.manufacturerId = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.manufacturerProductType = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.manufacturerProductId = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.nonManagmentJobs = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.secureInclusion = True
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 0 (0x00000000)
[2016-04-23 08:27:15.619] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = 0 (0x00000000)
[2016-04-23 08:27:15.620] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = 0 (0x00000000)
[2016-04-23 08:27:15.620] [D] [zway] SETDATA controller.data.countJobs = False
[2016-04-23 08:27:15.620] [D] [zway] SETDATA controller.data.softwareRevisionVersion = "v2.2.2"
[2016-04-23 08:27:15.620] [D] [zway] SETDATA controller.data.softwareRevisionId = "d2212e41b6487bf6efe6df730db05571edc64915"
[2016-04-23 08:27:15.620] [D] [zway] SETDATA controller.data.softwareRevisionDate = "2016-04-11 11:00:34 +0300"
[2016-04-23 08:27:15.620] [D] [zway] SETDATA controller.data.functionClasses = int[56]
[2016-04-23 08:27:15.620] [D] [zway] [00] 2
[2016-04-23 08:27:15.620] [D] [zway] [01] 3
[2016-04-23 08:27:15.620] [D] [zway] [02] 4
[2016-04-23 08:27:15.620] [D] [zway] [03] 5
[2016-04-23 08:27:15.620] [D] [zway] [04] 6
[2016-04-23 08:27:15.620] [D] [zway] [05] 7
[2016-04-23 08:27:15.620] [D] [zway] [06] 8
[2016-04-23 08:27:15.620] [D] [zway] [07] 18
[2016-04-23 08:27:15.620] [D] [zway] [08] 19
[2016-04-23 08:27:15.620] [D] [zway] [09] 21
[2016-04-23 08:27:15.620] [D] [zway] [10] 22
[2016-04-23 08:27:15.620] [D] [zway] [11] 23
[2016-04-23 08:27:15.620] [D] [zway] [12] 32
[2016-04-23 08:27:15.620] [D] [zway] [13] 33
[2016-04-23 08:27:15.620] [D] [zway] [14] 34
[2016-04-23 08:27:15.620] [D] [zway] [15] 35
[2016-04-23 08:27:15.620] [D] [zway] [16] 36
[2016-04-23 08:27:15.620] [D] [zway] [17] 39
[2016-04-23 08:27:15.620] [D] [zway] [18] 41
[2016-04-23 08:27:15.620] [D] [zway] [19] 42
[2016-04-23 08:27:15.620] [D] [zway] [20] 43
[2016-04-23 08:27:15.620] [D] [zway] [21] 44
[2016-04-23 08:27:15.620] [D] [zway] [22] 45
[2016-04-23 08:27:15.620] [D] [zway] [23] 65
[2016-04-23 08:27:15.620] [D] [zway] [24] 66
[2016-04-23 08:27:15.620] [D] [zway] [25] 68
[2016-04-23 08:27:15.620] [D] [zway] [26] 70
[2016-04-23 08:27:15.620] [D] [zway] [27] 71
[2016-04-23 08:27:15.621] [D] [zway] [28] 72
[2016-04-23 08:27:15.621] [D] [zway] [29] 73
[2016-04-23 08:27:15.621] [D] [zway] [30] 74
[2016-04-23 08:27:15.621] [D] [zway] [31] 75
[2016-04-23 08:27:15.621] [D] [zway] [32] 76
[2016-04-23 08:27:15.621] [D] [zway] [33] 77
[2016-04-23 08:27:15.621] [D] [zway] [34] 80
[2016-04-23 08:27:15.621] [D] [zway] [35] 81
[2016-04-23 08:27:15.621] [D] [zway] [36] 82
[2016-04-23 08:27:15.621] [D] [zway] [37] 83
[2016-04-23 08:27:15.621] [D] [zway] [38] 84
[2016-04-23 08:27:15.621] [D] [zway] [39] 85
[2016-04-23 08:27:15.621] [D] [zway] [40] 86
[2016-04-23 08:27:15.621] [D] [zway] [41] 87
[2016-04-23 08:27:15.621] [D] [zway] [42] 94
[2016-04-23 08:27:15.621] [D] [zway] [43] 96
[2016-04-23 08:27:15.621] [D] [zway] [44] 97
[2016-04-23 08:27:15.621] [D] [zway] [45] 98
[2016-04-23 08:27:15.621] [D] [zway] [46] 99
[2016-04-23 08:27:15.621] [D] [zway] [47] 128
[2016-04-23 08:27:15.621] [D] [zway] [48] 186
[2016-04-23 08:27:15.621] [D] [zway] [49] 190
[2016-04-23 08:27:15.621] [D] [zway] [50] 210
[2016-04-23 08:27:15.621] [D] [zway] [51] 211
[2016-04-23 08:27:15.621] [D] [zway] [52] 242
[2016-04-23 08:27:15.621] [D] [zway] [53] 243
[2016-04-23 08:27:15.621] [D] [zway] [54] 244
[2016-04-23 08:27:15.621] [D] [zway] [55] 245
[2016-04-23 08:27:15.621] [D] [zway] SETDATA controller.data.functionClassesNames = string[56]
[2016-04-23 08:27:15.621] [D] [zway] [00] "SerialAPIGetInitData"
[2016-04-23 08:27:15.621] [D] [zway] [01] "SerialAPIApplicationNodeInformation"
[2016-04-23 08:27:15.621] [D] [zway] [02] "ApplicationCommandHandler"
[2016-04-23 08:27:15.621] [D] [zway] [03] "GetControllerCapabilities"
[2016-04-23 08:27:15.621] [D] [zway] [04] "SerialAPISetTimeouts"
[2016-04-23 08:27:15.621] [D] [zway] [05] "GetSerialAPICapabilities"
[2016-04-23 08:27:15.621] [D] [zway] [06] "SerialAPISoftReset"
[2016-04-23 08:27:15.621] [D] [zway] [07] "SendNodeInformation"
[2016-04-23 08:27:15.621] [D] [zway] [08] "SendData"
[2016-04-23 08:27:15.621] [D] [zway] [09] "GetVersion"
[2016-04-23 08:27:15.621] [D] [zway] [10] "SendDataAbort"
[2016-04-23 08:27:15.621] [D] [zway] [11] "RFPowerLevelSet"
[2016-04-23 08:27:15.621] [D] [zway] [12] "GetHomeId"
[2016-04-23 08:27:15.621] [D] [zway] [13] "MemoryGetByte"
[2016-04-23 08:27:15.621] [D] [zway] [14] "MemoryPutByte"
[2016-04-23 08:27:15.621] [D] [zway] [15] "MemoryGetBuffer"
[2016-04-23 08:27:15.621] [D] [zway] [16] "MemoryPutBuffer"
[2016-04-23 08:27:15.622] [D] [zway] [17] "FlashAutoProgSet"
[2016-04-23 08:27:15.622] [D] [zway] [18] "NVMGetId"
[2016-04-23 08:27:15.622] [D] [zway] [19] "NVMExtReadLongBuffer"
[2016-04-23
08:27:15.622] [D] [zway] [20] "NVMExtWriteLongBuffer"
[2016-04-23 08:27:15.622] [D] [zway] [21] "NVMExtReadLongByte"
[2016-04-23 08:27:15.622] [D] [zway] [22] "NVMExtWriteLongByte"
[2016-04-23 08:27:15.622] [D] [zway] [23] "GetNodeProtocolInformation"
[2016-04-23 08:27:15.622] [D] [zway] [24] "SetDefault"
[2016-04-23 08:27:15.622] [D] [zway] [25] "ReplicationReceiveComplete"
[2016-04-23 08:27:15.622] [D] [zway] [26] "AssignReturnRoute"
[2016-04-23 08:27:15.622] [D] [zway] [27] "DeleteReturnRoute"
[2016-04-23 08:27:15.622] [D] [zway] [28] "RequestNodeNeighbourUpdate"
[2016-04-23 08:27:15.622] [D] [zway] [29] "ApplicationNodeUpdate"
[2016-04-23 08:27:15.622] [D] [zway] [30] "AddNodeToNetwork"
[2016-04-23 08:27:15.622] [D] [zway] [31] "RemoveNodeFromNetwork"
[2016-04-23 08:27:15.622] [D] [zway] [32] "CreateNewPrimary"
[2016-04-23 08:27:15.622] [D] [zway] [33] "ControllerChange"
[2016-04-23 08:27:15.622] [D] [zway] [34] "SetLearnMode"
[2016-04-23 08:27:15.622] [D] [zway] [35] "AssignSUCReturnRoute"
[2016-04-23 08:27:15.622] [D] [zway] [36] "EnableSUC"
[2016-04-23 08:27:15.622] [D] [zway] [37] "RequestNetworkUpdate"
[2016-04-23 08:27:15.622] [D] [zway] [38] "SetSUCNodeId"
[2016-04-23 08:27:15.622] [D] [zway] [39] "DeleteSUCReturnRoute"
[2016-04-23 08:27:15.622] [D] [zway] [40] "GetSUCNodeId"
[2016-04-23 08:27:15.622] [D] [zway] [41] "SendSUCNodeId"
[2016-04-23 08:27:15.622] [D] [zway] [42] "ExploreRequestInclusion"
[2016-04-23 08:27:15.622] [D] [zway] [43] "RequestNodeInformation"
[2016-04-23 08:27:15.622] [D] [zway] [44] "RemoveFailedNode"
[2016-04-23 08:27:15.622] [D] [zway] [45] "IsFailedNode"
[2016-04-23 08:27:15.622] [D] [zway] [46] "ReplaceFailedNode"
[2016-04-23 08:27:15.622] [D] [zway] [47] "GetRoutingTableLine"
[2016-04-23 08:27:15.622] [D] [zway] [48] "RFPowerLevelGet"
[2016-04-23 08:27:15.622] [D] [zway] [49] "SendTestFrame"
[2016-04-23 08:27:15.622] [D] [zway] [50] "WatchDogStart"
[2016-04-23 08:27:15.622] [D] [zway] [51] "WatchDogStop"
[2016-04-23 08:27:15.622] [D] [zway] [52] "ZMEFreqChange"
[2016-04-23 08:27:15.622] [D] [zway] [53] "ZMERestore"
[2016-04-23 08:27:15.622] [D] [zway] [54] "ZMEBootloaderFlash"
[2016-04-23 08:27:15.622] [D] [zway] [55] "ZMECapabilities"
[2016-04-23 08:27:15.623] [D] [zway] Opened device: /dev/ttyAMA0
[2016-04-23 08:27:15.623] [D] [zway] Worker thread successfully created
[2016-04-23 08:27:15.623] [D] [zway] Worker thread entry point
[2016-04-23 08:27:15.623] [I] [zway] Adding job: Get controller info and supported function classes
[2016-04-23 08:27:15.633] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2016-04-23 08:27:15.634] [D] [zway] RECEIVED CAN
[2016-04-23 08:27:15.936] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2016-04-23 08:27:15.936] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2016-04-23 08:27:15.937] [D] [zway] RECEIVED CAN
[2016-04-23 08:27:16.239] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2016-04-23 08:27:16.239] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2016-04-23 08:27:16.240] [D] [zway] RECEIVED CAN
[2016-04-23 08:27:16.543] [I] [zway] Job 0x07 (Get controller info and supported function classes): No ACK received before timeout
[2016-04-23 08:27:16.543] [W] [zway] Job 0x07 (Get controller info and supported function classes) dropped: too many resends
[2016-04-23 08:27:16.543] [D] [zway] Job 0x07 (Get controller info and supported function classes): fail
[2016-04-23 08:27:16.543] [C] [zway] Get Serial API Capabilities returned zero.
[2016-04-23 08:27:16.543] [I] [zway] Removing job: Get controller info and supported function classes
[2016-04-23 08:27:16.553] [D] [zway] Worker thread exit point
[2016-04-23 08:27:16.553] [D] [zway] Worker thread successfully finished
[2016-04-23 08:27:16.554] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2016-04-23 08:27:16.571] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Looking at these lines, I would suspect a faulty RaZberry module:

Hi evansnp,

i think the same. Before a view days i wrote a mail with the error description to the manufacter "razberry@zwave.me", but no feedback.

Is "razberry@zwave.me" the manufacter and where is the support ?

So i decide today to contact the supplier, to send me a new razberry daughterboard.


Regards Michael