Page 1 of 1

Notification: critical (z-wave): Cannot start Z-Wave binding

Posted: 03 Jan 2017 15:53
by freeman
Добрый день,

Вижу что ответы на форуме получить особо не получается, но вдруг. Словил такую ошибку в логе.

Code: Select all

[2017-01-03 09:26:27.720] [D] [zway] ---------------------------
[2017-01-03 09:26:27.720] [D] [zway] Initialization done
[2017-01-03 09:26:27.720] [D] [zway] Job 0x07 (Get controller info and supported function classes): success
[2017-01-03 09:26:27.720] [C] [zway] Device is not compatible. Terminating...
[2017-01-03 09:26:27.720] [I] [zway] Removing job: Get controller info and supported function classes
[2017-01-03 09:26:27.734] [D] [zway] Worker thread exit point
[2017-01-03 09:26:27.735] [D] [zway] Worker thread successfully finished
[2017-01-03 09:26:27.736] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2017-01-03 09:26:27.768] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed
Видел статью http://razberry.z-wave.me/index.php?id=29 вижу что полной перезагрузкой можно будет решить и в прошлый раз, где-то месяц назад вроде помогло. Но хочется понять что могло послужить причиной ошибки (может какие-то некорректные действия с нашей стороны), почему на работающей месяц системе вдруг вылетает такое.

Спасибо!

Re: Notification: critical (z-wave): Cannot start Z-Wave bin

Posted: 06 Jan 2017 19:37
by PoltoS
А что выше?

Re: Notification: critical (z-wave): Cannot start Z-Wave bin

Posted: 09 Jan 2017 14:24
by freeman
Снова словили эту проблему вчера. Перестали уходить команды на устройства. Нужно уточнить что каждые пять секунд наш скрипт отправляет команду на обновление состояния свитчей, это было сделано до меня чтобы узнавать об изменении состояния некоторых свитчей, которые по умолчанию не оповещали контролер об изменении своего состояния, я с этим сейчас разобрался, поэтому данное действие будет убрано. Написал чтобы было более понятно при каких условиях происходит такая ошибка.

Пример лога в процессе ошибки:

Code: Select all

[2017-01-03 09:10:19.860] [D] [zway] SENDING (cb 0x7c): ( 01 0A 00 13 0C 03 25 01 00 25 7C 94 )
[2017-01-03 09:10:19.869] [D] [zway] RECEIVED ACK
[2017-01-03 09:10:19.869] [D] [zway] RECEIVED: ( 01 04 01 13 00 E9 )
[2017-01-03 09:10:19.869] [D] [zway] SENT ACK
[2017-01-03 09:10:19.869] [I] [zway] Job 0x13 (SwitchBinary Set): Could not be delivered to Z-Wave stack
[2017-01-03 09:10:19.869] [D] [zway] SENDING (cb 0x7d): ( 01 0A 00 13 0C 03 25 01 00 25 7D 95 )
[2017-01-03 09:10:19.878] [D] [zway] RECEIVED ACK
[2017-01-03 09:10:19.879] [D] [zway] RECEIVED: ( 01 04 01 13 00 E9 )
[2017-01-03 09:10:19.879] [D] [zway] SENT ACK
[2017-01-03 09:10:19.879] [I] [zway] Job 0x13 (SwitchBinary Set): Could not be delivered to Z-Wave stack
[2017-01-03 09:10:19.879] [I] [zway] Job 0x13 (SwitchBinary Set): Removing job due to too much retransmitions
[2017-01-03 09:10:19.879] [D] [zway] Job 0x13 (SwitchBinary Set): fail
[2017-01-03 09:10:19.879] [I] [zway] Removing job: SwitchBinary Set
[2017-01-03 09:10:19.879] [D] [zway] SENDING (cb 0x7e): ( 01 09 00 13 0C 02 25 02 25 7E 97 )
[2017-01-03 09:10:19.888] [D] [zway] RECEIVED ACK
[2017-01-03 09:10:19.889] [D] [zway] RECEIVED: ( 01 04 01 13 00 E9 )
[2017-01-03 09:10:19.889] [D] [zway] SENT ACK
[2017-01-03 09:10:19.889] [I] [zway] Job 0x13 (SwitchBinary Get): Could not be delivered to Z-Wave stack
[2017-01-03 09:10:19.889] [D] [zway] SENDING (cb 0x7f): ( 01 09 00 13 0C 02 25 02 25 7F 96 )
[2017-01-03 09:10:19.898] [D] [zway] RECEIVED ACK
[2017-01-03 09:10:19.898] [D] [zway] RECEIVED: ( 01 04 01 13 00 E9 )
[2017-01-03 09:10:19.898] [D] [zway] SENT ACK
[2017-01-03 09:10:19.898] [I] [zway] Job 0x13 (SwitchBinary Get): Could not be delivered to Z-Wave stack
[2017-01-03 09:10:19.899] [D] [zway] SENDING (cb 0x80): ( 01 09 00 13 0C 02 25 02 25 80 69 )
[2017-01-03 09:10:19.908] [D] [zway] RECEIVED ACK
[2017-01-03 09:10:19.908] [D] [zway] RECEIVED: ( 01 04 01 13 00 E9 )
[2017-01-03 09:10:19.908] [D] [zway] SENT ACK
[2017-01-03 09:10:19.908] [I] [zway] Job 0x13 (SwitchBinary Get): Could not be delivered to Z-Wave stack
[2017-01-03 09:10:19.908] [I] [zway] Job 0x13 (SwitchBinary Get): Removing job due to too much retransmitions
[2017-01-03 09:10:19.908] [D] [zway] Job 0x13 (SwitchBinary Get): fail
[2017-01-03 09:10:19.908] [I] [zway] Removing job: SwitchBinary Get
[2017-01-03 09:10:19.946] [I] [zway] Adding job: SwitchMultilevel Get
[2017-01-03 09:10:19.950] [D] [zway] SENDING (cb 0x81): ( 01 09 00 13 0D 02 26 02 25 81 6A )
[2017-01-03 09:10:19.952] [D] [zway] RECEIVED ACK
[2017-01-03 09:10:19.953] [D] [zway] RECEIVED: ( 01 04 01 13 00 E9 )
[2017-01-03 09:10:19.953] [D] [zway] SENT ACK
[2017-01-03 09:10:19.953] [I] [zway] Job 0x13 (SwitchMultilevel Get): Could not be delivered to Z-Wave stack
[2017-01-03 09:10:19.953] [D] [zway] SENDING (cb 0x82): ( 01 09 00 13 0D 02 26 02 25 82 69 )
[2017-01-03 09:10:19.960] [D] [zway] RECEIVED ACK
[2017-01-03 09:10:19.960] [D] [zway] RECEIVED: ( 01 04 01 13 00 E9 )
[2017-01-03 09:10:19.960] [D] [zway] SENT ACK
[2017-01-03 09:10:19.960] [I] [zway] Job 0x13 (SwitchMultilevel Get): Could not be delivered to Z-Wave stack
[2017-01-03 09:10:19.961] [D] [zway] SENDING (cb 0x83): ( 01 09 00 13 0D 02 26 02 25 83 68 )
[2017-01-03 09:10:19.970] [D] [zway] RECEIVED ACK
[2017-01-03 09:10:19.970] [D] [zway] RECEIVED: ( 01 04 01 13 00 E9 )
[2017-01-03 09:10:19.971] [D] [zway] SENT ACK
[2017-01-03 09:10:19.971] [I] [zway] Job 0x13 (SwitchMultilevel Get): Could not be delivered to Z-Wave stack
[2017-01-03 09:10:19.971] [I] [zway] Job 0x13 (SwitchMultilevel Get): Removing job due to too much retransmitions
[2017-01-03 09:10:19.971] [D] [zway] Job 0x13 (SwitchMultilevel Get): fail
[2017-01-03 09:10:19.971] [I] [zway] Removing job: SwitchMultilevel Get
[2017-01-03 09:10:20.443] [D] [zway] Job 0x13: deleted from queue
[2017-01-03 09:10:20.454] [D] [zway] Job 0x13: deleted from queue
[2017-01-03 09:10:20.464] [D] [zway] Job 0x13: deleted from queue
[2017-01-03 09:10:20.526] [D] [zway] Job 0x13: deleted from queue
[2017-01-03 09:10:20.588] [D] [zway] Job 0x13: deleted from queue
Полный лог загрузки:

Code: Select all

[2017-01-03 09:21:43.615] [I] [core] Restarting Z-Wave binding
[2017-01-03 09:21:43.773] [D] [zway] SETDATA controller.data = Empty
[2017-01-03 09:21:43.773] [D] [zway] SETDATA controller.data.nodeId = Empty
[2017-01-03 09:21:43.773] [D] [zway] SETDATA controller.data.nodeId = 0 (0x00000000)
[2017-01-03 09:21:43.773] [D] [zway] SETDATA controller.data.homeId = Empty
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.homeId = 0 (0x00000000)
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.SUCNodeId = Empty
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.SUCNodeId = 0 (0x00000000)
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.isPrimary = Empty
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.isPrimary = False
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.isInOthersNetwork = Empty
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.isInOthersNetwork = False
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.isRealPrimary = Empty
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.isRealPrimary = False
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.isSUC = Empty
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.isSUC = False
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.SISPresent = Empty
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.SISPresent = False
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.libType = Empty
[2017-01-03 09:21:43.774] [D] [zway] SETDATA controller.data.libType = ""
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.SDK = Empty
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.SDK = ""
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWlibMajor = Empty
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWlibMajor = 0 (0x00000000)
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWlibMinor = Empty
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWlibMinor = 0 (0x00000000)
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWLib = Empty
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWLib = 0 (0x00000000)
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWVersion = Empty
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWVersion = 0 (0x00000000)
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWaveChip = Empty
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.ZWaveChip = ""
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.APIVersion = Empty
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.APIVersion = ""
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.manufacturerId = Empty
[2017-01-03 09:21:43.775] [D] [zway] SETDATA controller.data.manufacturerId = 0 (0x00000000)
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.vendor = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.vendor = ""
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.manufacturerProductType = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.manufacturerProductType = 0 (0x00000000)
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.manufacturerProductId = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.manufacturerProductId = 0 (0x00000000)
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.bootloaderCRC = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.firmwareCRC = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.capabilities = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.controllerState = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.nonManagmentJobs = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.nonManagmentJobs = 0 (0x00000000)
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.lastIncludedDevice = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.lastIncludedDevice = 0 (0x00000000)
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.lastExcludedDevice = Empty
[2017-01-03 09:21:43.776] [D] [zway] SETDATA controller.data.lastExcludedDevice = 0 (0x00000000)
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.secureInclusion = Empty
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.secureInclusion = True
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = Empty
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.oldSerialAPIAckTimeout10ms = 0 (0x00000000)
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = Empty
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.oldSerialAPIByteTimeout10ms = 0 (0x00000000)
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = Empty
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.curSerialAPIAckTimeout10ms = 0 (0x00000000)
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = Empty
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.curSerialAPIByteTimeout10ms = 0 (0x00000000)
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.countJobs = Empty
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.countJobs = False
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.memoryGetAddress = Empty
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.memoryGetData = Empty
[2017-01-03 09:21:43.777] [D] [zway] SETDATA controller.data.functionClasses = Empty
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.functionClassesNames = Empty
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.softwareRevisionVersion = Empty
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.softwareRevisionVersion = "v2.2.4"
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.softwareRevisionId = Empty
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.softwareRevisionId = "dbad9efdbcae16962cff77f51d025f2db8d96311"
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.softwareRevisionDate = Empty
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.softwareRevisionDate = "2016-10-22 02:33:36 +0300"
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.uuid = Empty
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.caps = Empty
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.frequency = Empty
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.frequency = ""
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.deviceRelaxDelay = Empty
[2017-01-03 09:21:43.778] [D] [zway] SETDATA controller.data.deviceRelaxDelay = 5 (0x00000005)
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.incomingPacket = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.incomingPacket.nodeId = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.incomingPacket.RSSI = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.incomingPacket.frameType = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.delivered = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.deliveryTime = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.packetLength = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.nodeId = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.returnRSSI = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.hops = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.returnChannel = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.txChannel = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.speed = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.schemeState = Empty
[2017-01-03 09:21:43.779] [D] [zway] SETDATA controller.data.outgoingPacket.tries = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.outgoingPacket.lastFailPath = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel1 = 127 (0x0000007f)
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel2 = 127 (0x0000007f)
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.backgroundRSSI.channel3 = 127 (0x0000007f)
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.RFTxFrames = 0 (0x00000000)
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.RFTxLBTBackOffs = 0 (0x00000000)
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.RFRxFrames = 0 (0x00000000)
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = Empty
[2017-01-03 09:21:43.780] [D] [zway] SETDATA controller.data.statistics.RFRxLRCErrors = 0 (0x00000000)
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = Empty
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.statistics.RFRxCRC16Errors = 0 (0x00000000)
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = Empty
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.statistics.RFRxForeignHomeID = 0 (0x00000000)
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.statistics.priorityRoute = Empty
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.statistics.priorityRoute.dstNodeId = Empty
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.statistics.priorityRoute.routeType = Empty
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.statistics.priorityRoute.speed = Empty
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.statistics.priorityRoute.hops = Empty
[2017-01-03 09:21:43.781] [D] [zway] SETDATA controller.data.functionClasses = int[62]
[2017-01-03 09:21:43.781] [D] [zway]   [00] 2
[2017-01-03 09:21:43.782] [D] [zway]   [01] 3
[2017-01-03 09:21:43.782] [D] [zway]   [02] 4
[2017-01-03 09:21:43.782] [D] [zway]   [03] 5
[2017-01-03 09:21:43.782] [D] [zway]   [04] 6
[2017-01-03 09:21:43.782] [D] [zway]   [05] 7
[2017-01-03 09:21:43.782] [D] [zway]   [06] 8
[2017-01-03 09:21:43.782] [D] [zway]   [07] 11
[2017-01-03 09:21:43.782] [D] [zway]   [08] 18
[2017-01-03 09:21:43.782] [D] [zway]   [09] 19
[2017-01-03 09:21:43.782] [D] [zway]   [10] 21
[2017-01-03 09:21:43.782] [D] [zway]   [11] 22
[2017-01-03 09:21:43.782] [D] [zway]   [12] 23
[2017-01-03 09:21:43.782] [D] [zway]   [13] 32
[2017-01-03 09:21:43.782] [D] [zway]   [14] 33
[2017-01-03 09:21:43.782] [D] [zway]   [15] 34
[2017-01-03 09:21:43.782] [D] [zway]   [16] 35
[2017-01-03 09:21:43.782] [D] [zway]   [17] 36
[2017-01-03 09:21:43.782] [D] [zway]   [18] 39
[2017-01-03 09:21:43.782] [D] [zway]   [19] 41
[2017-01-03 09:21:43.782] [D] [zway]   [20] 42
[2017-01-03 09:21:43.782] [D] [zway]   [21] 43
[2017-01-03 09:21:43.782] [D] [zway]   [22] 44
[2017-01-03 09:21:43.783] [D] [zway]   [23] 45
[2017-01-03 09:21:43.783] [D] [zway]   [24] 57
[2017-01-03 09:21:43.783] [D] [zway]   [25] 58
[2017-01-03 09:21:43.783] [D] [zway]   [26] 59
[2017-01-03 09:21:43.783] [D] [zway]   [27] 65
[2017-01-03 09:21:43.783] [D] [zway]   [28] 66
[2017-01-03 09:21:43.783] [D] [zway]   [29] 68
[2017-01-03 09:21:43.783] [D] [zway]   [30] 70
[2017-01-03 09:21:43.783] [D] [zway]   [31] 71
[2017-01-03 09:21:43.783] [D] [zway]   [32] 72
[2017-01-03 09:21:43.783] [D] [zway]   [33] 73
[2017-01-03 09:21:43.783] [D] [zway]   [34] 74
[2017-01-03 09:21:43.783] [D] [zway]   [35] 75
[2017-01-03 09:21:43.783] [D] [zway]   [36] 76
[2017-01-03 09:21:43.783] [D] [zway]   [37] 77
[2017-01-03 09:21:43.783] [D] [zway]   [38] 80
[2017-01-03 09:21:43.783] [D] [zway]   [39] 81
[2017-01-03 09:21:43.783] [D] [zway]   [40] 82
[2017-01-03 09:21:43.783] [D] [zway]   [41] 83
[2017-01-03 09:21:43.783] [D] [zway]   [42] 84
[2017-01-03 09:21:43.783] [D] [zway]   [43] 85
[2017-01-03 09:21:43.783] [D] [zway]   [44] 86
[2017-01-03 09:21:43.784] [D] [zway]   [45] 87
[2017-01-03 09:21:43.784] [D] [zway]   [46] 94
[2017-01-03 09:21:43.784] [D] [zway]   [47] 96
[2017-01-03 09:21:43.784] [D] [zway]   [48] 97
[2017-01-03 09:21:43.784] [D] [zway]   [49] 98
[2017-01-03 09:21:43.784] [D] [zway]   [50] 99
[2017-01-03 09:21:43.784] [D] [zway]   [51] 128
[2017-01-03 09:21:43.784] [D] [zway]   [52] 146
[2017-01-03 09:21:43.784] [D] [zway]   [53] 147
[2017-01-03 09:21:43.784] [D] [zway]   [54] 186
[2017-01-03 09:21:43.784] [D] [zway]   [55] 190
[2017-01-03 09:21:43.784] [D] [zway]   [56] 210
[2017-01-03 09:21:43.784] [D] [zway]   [57] 211
[2017-01-03 09:21:43.784] [D] [zway]   [58] 242
[2017-01-03 09:21:43.784] [D] [zway]   [59] 243
[2017-01-03 09:21:43.784] [D] [zway]   [60] 244
[2017-01-03 09:21:43.784] [D] [zway]   [61] 245
[2017-01-03 09:21:43.784] [D] [zway] SETDATA controller.data.functionClassesNames = string[62]
[2017-01-03 09:21:43.784] [D] [zway]   [00] "SerialAPIGetInitData"
[2017-01-03 09:21:43.785] [D] [zway]   [01] "SerialAPIApplicationNodeInformation"
[2017-01-03 09:21:43.785] [D] [zway]   [02] "ApplicationCommandHandler"
[2017-01-03 09:21:43.785] [D] [zway]   [03] "GetControllerCapabilities"
[2017-01-03 09:21:43.785] [D] [zway]   [04] "SerialAPISetTimeouts"
[2017-01-03 09:21:43.785] [D] [zway]   [05] "GetSerialAPICapabilities"
[2017-01-03 09:21:43.785] [D] [zway]   [06] "SerialAPISoftReset"
[2017-01-03 09:21:43.785] [D] [zway]   [07] "SerialAPISetup"
[2017-01-03 09:21:43.785] [D] [zway]   [08] "SendNodeInformation"
[2017-01-03 09:21:43.785] [D] [zway]   [09] "SendData"
[2017-01-03 09:21:43.785] [D] [zway]   [10] "GetVersion"
[2017-01-03 09:21:43.785] [D] [zway]   [11] "SendDataAbort"
[2017-01-03 09:21:43.785] [D] [zway]   [12] "RFPowerLevelSet"
[2017-01-03 09:21:43.785] [D] [zway]   [13] "GetHomeId"
[2017-01-03 09:21:43.785] [D] [zway]   [14] "MemoryGetByte"
[2017-01-03 09:21:43.785] [D] [zway]   [15] "MemoryPutByte"
[2017-01-03 09:21:43.785] [D] [zway]   [16] "MemoryGetBuffer"
[2017-01-03 09:21:43.785] [D] [zway]   [17] "MemoryPutBuffer"
[2017-01-03 09:21:43.785] [D] [zway]   [18] "FlashAutoProgSet"
[2017-01-03 09:21:43.785] [D] [zway]   [19] "NVMGetId"
[2017-01-03 09:21:43.785] [D] [zway]   [20] "NVMExtReadLongBuffer"
[2017-01-03 09:21:43.785] [D] [zway]   [21] "NVMExtWriteLongBuffer"
[2017-01-03 09:21:43.785] [D] [zway]   [22] "NVMExtReadLongByte"
[2017-01-03 09:21:43.786] [D] [zway]   [23] "NVMExtWriteLongByte"
[2017-01-03 09:21:43.786] [D] [zway]   [24] "ClearNetworkStats"
[2017-01-03 09:21:43.786] [D] [zway]   [25] "GetNetworkStats"
[2017-01-03 09:21:43.786] [D] [zway]   [26] "GetBackgroundRSSI"
[2017-01-03 09:21:43.786] [D] [zway]   [27] "GetNodeProtocolInformation"
[2017-01-03 09:21:43.786] [D] [zway]   [28] "SetDefault"
[2017-01-03 09:21:43.786] [D] [zway]   [29] "ReplicationReceiveComplete"
[2017-01-03 09:21:43.786] [D] [zway]   [30] "AssignReturnRoute"
[2017-01-03 09:21:43.786] [D] [zway]   [31] "DeleteReturnRoute"
[2017-01-03 09:21:43.786] [D] [zway]   [32] "RequestNodeNeighbourUpdate"
[2017-01-03 09:21:43.786] [D] [zway]   [33] "ApplicationNodeUpdate"
[2017-01-03 09:21:43.786] [D] [zway]   [34] "AddNodeToNetwork"
[2017-01-03 09:21:43.786] [D] [zway]   [35] "RemoveNodeFromNetwork"
[2017-01-03 09:21:43.786] [D] [zway]   [36] "CreateNewPrimary"
[2017-01-03 09:21:43.786] [D] [zway]   [37] "ControllerChange"
[2017-01-03 09:21:43.786] [D] [zway]   [38] "SetLearnMode"
[2017-01-03 09:21:43.786] [D] [zway]   [39] "AssignSUCReturnRoute"
[2017-01-03 09:21:43.786] [D] [zway]   [40] "EnableSUC"
[2017-01-03 09:21:43.786] [D] [zway]   [41] "RequestNetworkUpdate"
[2017-01-03 09:21:43.786] [D] [zway]   [42] "SetSUCNodeId"
[2017-01-03 09:21:43.786] [D] [zway]   [43] "DeleteSUCReturnRoute"
[2017-01-03 09:21:43.786] [D] [zway]   [44] "GetSUCNodeId"
[2017-01-03 09:21:43.787] [D] [zway]   [45] "SendSUCNodeId"
[2017-01-03 09:21:43.787] [D] [zway]   [46] "ExploreRequestInclusion"
[2017-01-03 09:21:43.787] [D] [zway]   [47] "RequestNodeInformation"
[2017-01-03 09:21:43.787] [D] [zway]   [48] "RemoveFailedNode"
[2017-01-03 09:21:43.787] [D] [zway]   [49] "IsFailedNode"
[2017-01-03 09:21:43.787] [D] [zway]   [50] "ReplaceFailedNode"
[2017-01-03 09:21:43.787] [D] [zway]   [51] "GetRoutingTableLine"
[2017-01-03 09:21:43.787] [D] [zway]   [52] "GetPriorityRoute"
[2017-01-03 09:21:43.787] [D] [zway]   [53] "SetPriorityRoute"
[2017-01-03 09:21:43.787] [D] [zway]   [54] "RFPowerLevelGet"
[2017-01-03 09:21:43.787] [D] [zway]   [55] "SendTestFrame"
[2017-01-03 09:21:43.787] [D] [zway]   [56] "WatchDogStart"
[2017-01-03 09:21:43.787] [D] [zway]   [57] "WatchDogStop"
[2017-01-03 09:21:43.787] [D] [zway]   [58] "ZMEFreqChange"
[2017-01-03 09:21:43.787] [D] [zway]   [59] "ZMERestore"
[2017-01-03 09:21:43.787] [D] [zway]   [60] "ZMEBootloaderFlash"
[2017-01-03 09:21:43.787] [D] [zway]   [61] "ZMECapabilities"
[2017-01-03 09:21:43.788] [D] [zway] Opened device: /dev/ttyAMA0
[2017-01-03 09:21:43.788] [D] [zway] Worker thread successfully created
[2017-01-03 09:21:43.788] [D] [zway] Worker thread entry point
[2017-01-03 09:21:43.789] [I] [zway] Adding job: Get controller info and supported function classes
[2017-01-03 09:21:43.798] [D] [zway] SENDING: ( 01 03 00 07 FB )
[2017-01-03 09:21:43.800] [D] [zway] RECEIVED ACK
[2017-01-03 09:21:43.808] [D] [zway] RECEIVED: ( 01 2B 01 07 00 00 00 00 00 00 00 00 00 00 00 00 02 00 00 00 00 09 00 01 06 07 01 00 00 00 00 00 00 00 00 00 00 00 00 5C 4D FF 00 32 04 )
[2017-01-03 09:21:43.808] [D] [zway] SENT ACK
[2017-01-03 09:21:43.808] [D] [zway] SETDATA controller.data.APIVersion = "00.00"
[2017-01-03 09:21:43.808] [D] [zway] SETDATA controller.data.manufacturerId = 0 (0x00000000)
[2017-01-03 09:21:43.808] [D] [zway] SETDATA controller.data.manufacturerProductType = 0 (0x00000000)
[2017-01-03 09:21:43.808] [D] [zway] SETDATA controller.data.manufacturerProductId = 0 (0x00000000)
[2017-01-03 09:21:43.810] [D] [zway] SETDATA controller.data.vendor = "Sigma Designs"
[2017-01-03 09:21:43.811] [D] [zway] Supported Function Classes:
[2017-01-03 09:21:43.811] [D] [zway] ---------------------------
[2017-01-03 09:21:43.811] [D] [zway] 0x22 MemoryPutByte
[2017-01-03 09:21:43.811] [D] [zway] 0x49 ApplicationNodeUpdate
[2017-01-03 09:21:43.811] [D] [zway] 0x4c CreateNewPrimary
[2017-01-03 09:21:43.811] [D] [zway] 0x59 UNKNOWN
[2017-01-03 09:21:43.811] [D] [zway] 0x62 IsFailedNode
[2017-01-03 09:21:43.811] [D] [zway] 0x63 ReplaceFailedNode
[2017-01-03 09:21:43.811] [D] [zway] 0x69 UNKNOWN
[2017-01-03 09:21:43.811] [D] [zway] 0x6a UNKNOWN
[2017-01-03 09:21:43.811] [D] [zway] 0x6b UNKNOWN
[2017-01-03 09:21:43.811] [D] [zway] 0x71 UNKNOWN
[2017-01-03 09:21:43.811] [D] [zway] 0xdb UNKNOWN
[2017-01-03 09:21:43.811] [D] [zway] 0xdc UNKNOWN
[2017-01-03 09:21:43.811] [D] [zway] 0xdd UNKNOWN
[2017-01-03 09:21:43.811] [D] [zway] 0xdf UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xe1 UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xe3 UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xe4 UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xe7 UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xe9 UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xea UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xeb UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xec UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xed UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xee UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xef UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xf0 UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xfa UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xfd UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] 0xfe UNKNOWN
[2017-01-03 09:21:43.812] [D] [zway] SETDATA controller.data.capabilities = int[29]
[2017-01-03 09:21:43.812] [D] [zway]   [00] 34
[2017-01-03 09:21:43.812] [D] [zway]   [01] 73
[2017-01-03 09:21:43.812] [D] [zway]   [02] 76
[2017-01-03 09:21:43.812] [D] [zway]   [03] 89
[2017-01-03 09:21:43.813] [D] [zway]   [04] 98
[2017-01-03 09:21:43.813] [D] [zway]   [05] 99
[2017-01-03 09:21:43.813] [D] [zway]   [06] 105
[2017-01-03 09:21:43.813] [D] [zway]   [07] 106
[2017-01-03 09:21:43.813] [D] [zway]   [08] 107
[2017-01-03 09:21:43.813] [D] [zway]   [09] 113
[2017-01-03 09:21:43.813] [D] [zway]   [10] 219
[2017-01-03 09:21:43.813] [D] [zway]   [11] 220
[2017-01-03 09:21:43.813] [D] [zway]   [12] 221
[2017-01-03 09:21:43.813] [D] [zway]   [13] 223
[2017-01-03 09:21:43.813] [D] [zway]   [14] 225
[2017-01-03 09:21:43.813] [D] [zway]   [15] 227
[2017-01-03 09:21:43.813] [D] [zway]   [16] 228
[2017-01-03 09:21:43.813] [D] [zway]   [17] 231
[2017-01-03 09:21:43.813] [D] [zway]   [18] 233
[2017-01-03 09:21:43.813] [D] [zway]   [19] 234
[2017-01-03 09:21:43.813] [D] [zway]   [20] 235
[2017-01-03 09:21:43.813] [D] [zway]   [21] 236
[2017-01-03 09:21:43.813] [D] [zway]   [22] 237
[2017-01-03 09:21:43.813] [D] [zway]   [23] 238
[2017-01-03 09:21:43.813] [D] [zway]   [24] 239
[2017-01-03 09:21:43.814] [D] [zway]   [25] 240
[2017-01-03 09:21:43.814] [D] [zway]   [26] 250
[2017-01-03 09:21:43.814] [D] [zway]   [27] 253
[2017-01-03 09:21:43.814] [D] [zway]   [28] 254
[2017-01-03 09:21:43.814] [D] [zway] ---------------------------
[2017-01-03 09:21:43.814] [D] [zway] Initialization done
[2017-01-03 09:21:43.814] [D] [zway] Job 0x07 (Get controller info and supported function classes): success
[2017-01-03 09:21:43.814] [C] [zway] Device is not compatible. Terminating...
[2017-01-03 09:21:43.814] [I] [zway] Removing job: Get controller info and supported function classes
[2017-01-03 09:21:43.828] [D] [zway] Worker thread exit point
[2017-01-03 09:21:43.829] [D] [zway] Worker thread successfully finished
[2017-01-03 09:21:43.830] [E] [zway] SaveData will not save data since it wasn't loaded. This is to prevent data loss.
[2017-01-03 09:21:43.861] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed

Re: Notification: critical (z-wave): Cannot start Z-Wave bin

Posted: 10 Jan 2017 00:37
by PoltoS
Ой, да у вас стик кучу нулей присылает, мы даже не понимаем, кто он такой...

Вот это его вылечит echo -ne "\x01\x03\x00\x08\xf4" > /dev/ttyAMA0 (иначе полное обесточивание на 1 сек тоже вылечит)

Это 3е поколение? С ним такое бывало. Или 5ое?

Re: Notification: critical (z-wave): Cannot start Z-Wave bin

Posted: 10 Jan 2017 14:10
by freeman
Добрый день,

Под стик вы подразумеваете z-wave контролер? В нашем случае это razberry board закрепленный на малинке.

Когда все работает на z-wave.my expert UI можно видеть такую информацию о железке

Code: Select all

Hardware
Vendor:	RaZberry by Z-Wave.Me
Vendors Product ID:	2 / 3
Z-Wave Chip:	ZW0301

Firmware
Library Type:	Static Controller
SDK Version:	4.54.01
Serial API Version:	04.02
Как я уже отмечал, проблема возникает в процессе работы устройства, последний раз между обнаружениями было где-то 5-6 дней (вылетало 3 января, потом 9-го обнаружилось). После этого в процессе загрузки видно в логе то что присылала раньше. Как я понимаю это какая-то проблема с железкой, которая появляется при определенных условиях (до этого работало стабильно больше 2 месяцев). Мне нужно либо предотвратить появление этих условий, либо сделать какое-то автоматическое восстановление работоспособности устройства. При следующем возникновении такой ситуации постараюсь проверить приведенную вами команду. Что мы с ее помощью делаем? Как я понимаю через /dev/ttyAMA0 идет коммуникация C библиотеки и чипа.

Может проблема в прошивке и есть возможность ее обновить?

Re: Notification: critical (z-wave): Cannot start Z-Wave bin

Posted: 10 Jan 2017 22:12
by PoltoS
Ага, это старые на 3ем поколении. У них изредка бывает, что стик/RaZberry сходит с ума и перестаёт доставлять пакеты и возвращает нули. Увы, проблему так и не нашли, т.к. обнаружена она была после окончания поддержки продукта. Уже более 2.5х лет выпускается версия на базе нового 5ого поколения чипов. У них очень много улучшений, настоятельно рекомендую обновиться. Там и обновление прошивок, и крутая антенна, и мощность выше, и чувствительность выше, и средства диагностики лучше. Всё лучше!

А та команда - это SerialAPISoftReset() - это перезагрузка чипа. Можно и из софта отправить (кнопка есть такая на вкладке Network Control

Re: Notification: critical (z-wave): Cannot start Z-Wave bin

Posted: 11 Jan 2017 13:13
by freeman
Спасибо за ответ. Думаю мы перейдем на более новый чип.

PS. кнопка Reset API находится на табе Control (если кто искать будет)

Re: Notification: critical (z-wave): Cannot start Z-Wave bin

Posted: 12 Jan 2017 10:43
by PoltoS
Точно! поправил