Not delivered to recipient

[alastairduncan]

I've a setup with a number of z-wave devices that has been working very well over the last couple of months. A few days ago all of the devices have problems. None of the switches can't be toggled. I tried to reinclude one of the switches but the razberry would not go into include mode. After resetting and rebooting the rpi i could reinclude but messages are still not getting through to the reincluded switch. On trying to reinclude other switches the controller is very slow to switch to include mode. Does anyone have any idea what is going wrong. Any help would be appreciated.

Alastair

Here are the logs:

Code: Select all

[2015-02-06 11:52:37.753] SETDATA controller.data.lastIncludedDevice = Empty
[2015-02-06 11:52:37.754] SETDATA controller.data.lastExcludedDevice = Empty
[2015-02-06 11:52:37.755] Job 0x4a (Add/re-include node to network): Ready to add - push button on the device to be added
[2015-02-06 11:52:37.756] SETDATA controller.data.controllerState = 1 (0x00000001)
[2015-02-06 11:52:53.994] Job 0x13: deleted from queue
[2015-02-06 11:52:57.530] RECEIVED: ( 01 12 00 4A 77 02 05 0B 04 10 01 25 20 27 72 86 32 85 70 D8 )
[2015-02-06 11:52:57.531] SENT ACK
[2015-02-06 11:52:57.532] Job 0x4a (Add/re-include node to network): Node found: #5
[2015-02-06 11:52:57.534] SETDATA controller.data.controllerState = 2 (0x00000002)
[2015-02-06 11:52:57.535] SETDATA devices.5.data.basicType = 4 (0x00000004)
[2015-02-06 11:52:57.537] SETDATA devices.5.data.genericType = 16 (0x00000010)
[2015-02-06 11:52:57.538] SETDATA devices.5.data.specificType = 1 (0x00000001)
[2015-02-06 11:52:57.543] SETDATA devices.5.data.deviceTypeString = "Binary Power Switch"
[2015-02-06 11:52:57.544] SETDATA devices.5.data.nodeInfoFrame = byte[8]
  ( 25 20 27 72 86 32 85 70 )
[2015-02-06 11:52:57.555] RECEIVED: ( 01 12 00 4A 77 03 05 0B 04 10 01 25 20 27 72 86 32 85 70 D9 )
[2015-02-06 11:52:57.556] SENT ACK
[2015-02-06 11:52:57.558] Job 0x4a (Add/re-include node to network): Adding slave: #5
[2015-02-06 11:52:57.560] SETDATA controller.data.controllerState = 3 (0x00000003)
[2015-02-06 11:52:57.561] SETDATA controller.data.lastIncludedDevice = 5 (0x00000005)
[2015-02-06 11:52:57.562] SETDATA devices.5.data.basicType = 4 (0x00000004)
[2015-02-06 11:52:57.563] SETDATA devices.5.data.genericType = 16 (0x00000010)
[2015-02-06 11:52:57.565] SETDATA devices.5.data.specificType = 1 (0x00000001)
[2015-02-06 11:52:57.569] SETDATA devices.5.data.deviceTypeString = "Binary Power Switch"
[2015-02-06 11:52:57.571] SETDATA devices.5.data.nodeInfoFrame = byte[8]
  ( 25 20 27 72 86 32 85 70 )
[2015-02-06 11:52:57.582] RECEIVED: ( 01 12 00 4A 77 05 05 0B 04 10 01 25 20 27 72 86 32 85 70 DF )
[2015-02-06 11:52:57.583] SENT ACK
[2015-02-06 11:52:57.585] Job 0x4a (Add/re-include node to network): Protocol done
[2015-02-06 11:52:57.586] SETDATA controller.data.controllerState = 4 (0x00000004)
[2015-02-06 11:52:57.587] Adding job: Add/re-include node to network
[2015-02-06 11:52:57.588] Loading Command Classes for device 5
[2015-02-06 11:52:57.594] Node 5:0 supports CC Basic
[2015-02-06 11:52:57.595] SETDATA devices.5.instances.0.commandClasses.32.data = Empty
[2015-02-06 11:52:57.596] SETDATA devices.5.instances.0.commandClasses.32.data.supported = Empty
[2015-02-06 11:52:57.597] SETDATA devices.5.instances.0.commandClasses.32.data.supported = True
[2015-02-06 11:52:57.598] SETDATA devices.5.instances.0.commandClasses.32.data.version = Empty
[2015-02-06 11:52:57.605] Deleting device switchBinary ZWayVDev_5:0:37
[2015-02-06 11:52:57.607] SETDATA devices.5.instances.0.commandClasses.32.data.version = 1 (0x00000001)
[2015-02-06 11:52:57.608] SETDATA devices.5.instances.0.commandClasses.32.data.security = Empty
[2015-02-06 11:52:57.609] SETDATA devices.5.instances.0.commandClasses.32.data.security = False
[2015-02-06 11:52:57.610] SETDATA devices.5.instances.0.commandClasses.32.data.interviewDone = Empty
[2015-02-06 11:52:57.612] SETDATA devices.5.instances.0.commandClasses.32.data.interviewCounter = Empty
[2015-02-06 11:52:57.613] SETDATA devices.5.instances.0.commandClasses.32.data.interviewDone = False
[2015-02-06 11:52:57.614] SETDATA devices.5.instances.0.commandClasses.32.data.interviewCounter = 10 (0x0000000a)
[2015-02-06 11:52:57.616] SETDATA devices.5.instances.0.commandClasses.32.data.level = Empty
[2015-02-06 11:52:57.617] Node 5:0 supports CC SwitchBinary
[2015-02-06 11:52:57.622] SETDATA devices.5.instances.0.commandClasses.37.data = Empty
[2015-02-06 11:52:57.623] SETDATA devices.5.instances.0.commandClasses.37.data.supported = Empty
[2015-02-06 11:52:57.624] SETDATA devices.5.instances.0.commandClasses.37.data.supported = True
[2015-02-06 11:52:57.625] SETDATA devices.5.instances.0.commandClasses.37.data.version = Empty
[2015-02-06 11:52:57.627] SETDATA devices.5.instances.0.commandClasses.37.data.version = 1 (0x00000001)
[2015-02-06 11:52:57.628] SETDATA devices.5.instances.0.commandClasses.37.data.security = Empty
[2015-02-06 11:52:57.629] SETDATA devices.5.instances.0.commandClasses.37.data.security = False
[2015-02-06 11:52:57.630] SETDATA devices.5.instances.0.commandClasses.37.data.interviewDone = Empty
[2015-02-06 11:52:57.632] SETDATA devices.5.instances.0.commandClasses.37.data.interviewCounter = Empty
[2015-02-06 11:52:57.633] SETDATA devices.5.instances.0.commandClasses.37.data.interviewDone = False
[2015-02-06 11:52:57.638] SETDATA devices.5.instances.0.commandClasses.37.data.interviewCounter = 10 (0x0000000a)
[2015-02-06 11:52:57.639] SETDATA devices.5.instances.0.commandClasses.37.data.level = Empty
[2015-02-06 11:52:57.640] Node 5:0 supports CC SwitchAll
[2015-02-06 11:52:57.642] SETDATA devices.5.instances.0.commandClasses.39.data = Empty
[2015-02-06 11:52:57.643] SETDATA devices.5.instances.0.commandClasses.39.data.supported = Empty
[2015-02-06 11:52:57.644] SETDATA devices.5.instances.0.commandClasses.39.data.supported = True
[2015-02-06 11:52:57.645] SETDATA devices.5.instances.0.commandClasses.39.data.version = Empty
[2015-02-06 11:52:57.647] SETDATA devices.5.instances.0.commandClasses.39.data.version = 1 (0x00000001)
[2015-02-06 11:52:57.648] SETDATA devices.5.instances.0.commandClasses.39.data.security = Empty
[2015-02-06 11:52:57.649] SETDATA devices.5.instances.0.commandClasses.39.data.security = False
[2015-02-06 11:52:57.655] SETDATA devices.5.instances.0.commandClasses.39.data.interviewDone = Empty
[2015-02-06 11:52:57.656] SETDATA devices.5.instances.0.commandClasses.39.data.interviewCounter = Empty
[2015-02-06 11:52:57.657] SETDATA devices.5.instances.0.commandClasses.39.data.interviewDone = False
[2015-02-06 11:52:57.659] SETDATA devices.5.instances.0.commandClasses.39.data.interviewCounter = 10 (0x0000000a)
[2015-02-06 11:52:57.660] SETDATA devices.5.instances.0.commandClasses.39.data.mode = Empty
[2015-02-06 11:52:57.661] SETDATA devices.5.instances.0.commandClasses.39.data.onOff = Empty
[2015-02-06 11:52:57.662] Node 5:0 supports CC Meter
[2015-02-06 11:52:57.664] SETDATA devices.5.instances.0.commandClasses.50.data = Empty
[2015-02-06 11:52:57.665] SETDATA devices.5.instances.0.commandClasses.50.data.supported = Empty
[2015-02-06 11:52:57.666] SETDATA devices.5.instances.0.commandClasses.50.data.supported = True
[2015-02-06 11:52:57.667] SETDATA devices.5.instances.0.commandClasses.50.data.version = Empty
[2015-02-06 11:52:57.668] SETDATA devices.5.instances.0.commandClasses.50.data.version = 1 (0x00000001)
[2015-02-06 11:52:57.669] SETDATA devices.5.instances.0.commandClasses.50.data.security = Empty
[2015-02-06 11:52:57.673] SETDATA devices.5.instances.0.commandClasses.50.data.security = False
[2015-02-06 11:52:57.674] SETDATA devices.5.instances.0.commandClasses.50.data.interviewDone = Empty
[2015-02-06 11:52:57.675] SETDATA devices.5.instances.0.commandClasses.50.data.interviewCounter = Empty
[2015-02-06 11:52:57.677] SETDATA devices.5.instances.0.commandClasses.50.data.interviewDone = False
[2015-02-06 11:52:57.678] SETDATA devices.5.instances.0.commandClasses.50.data.interviewCounter = 10 (0x0000000a)
[2015-02-06 11:52:57.679] SETDATA devices.5.instances.0.commandClasses.50.data.resettable = Empty
[2015-02-06 11:52:57.681] SETDATA devices.5.instances.0.commandClasses.50.data.scalemask = Empty
[2015-02-06 11:52:57.690] Node 5:0 supports CC Configuration
[2015-02-06 11:52:57.691] SETDATA devices.5.instances.0.commandClasses.112.data = Empty
[2015-02-06 11:52:57.692] SETDATA devices.5.instances.0.commandClasses.112.data.supported = Empty
[2015-02-06 11:52:57.693] SETDATA devices.5.instances.0.commandClasses.112.data.supported = True
[2015-02-06 11:52:57.695] SETDATA devices.5.instances.0.commandClasses.112.data.version = Empty
[2015-02-06 11:52:57.696] SETDATA devices.5.instances.0.commandClasses.112.data.version = 1 (0x00000001)
[2015-02-06 11:52:57.698] SETDATA devices.5.instances.0.commandClasses.112.data.security = Empty
[2015-02-06 11:52:57.699] SETDATA devices.5.instances.0.commandClasses.112.data.security = False
[2015-02-06 11:52:57.700] SETDATA devices.5.instances.0.commandClasses.112.data.interviewDone = Empty
[2015-02-06 11:52:57.702] SETDATA devices.5.instances.0.commandClasses.112.data.interviewCounter = Empty
[2015-02-06 11:52:57.703] SETDATA devices.5.instances.0.commandClasses.112.data.interviewDone = False
[2015-02-06 11:52:57.704] SETDATA devices.5.instances.0.commandClasses.112.data.interviewCounter = 10 (0x0000000a)
[2015-02-06 11:52:57.705] Node 5:0 supports CC ManufacturerSpecific
[2015-02-06 11:52:57.706] SETDATA devices.5.instances.0.commandClasses.114.data = Empty
[2015-02-06 11:52:57.706] SETDATA devices.5.instances.0.commandClasses.114.data.supported = Empty
[2015-02-06 11:52:57.707] SETDATA devices.5.instances.0.commandClasses.114.data.supported = True
[2015-02-06 11:52:57.708] SETDATA devices.5.instances.0.commandClasses.114.data.version = Empty
[2015-02-06 11:52:57.709] SETDATA devices.5.instances.0.commandClasses.114.data.version = 1 (0x00000001)
[2015-02-06 11:52:57.709] SETDATA devices.5.instances.0.commandClasses.114.data.security = Empty
[2015-02-06 11:52:57.711] SETDATA devices.5.instances.0.commandClasses.114.data.security = False
[2015-02-06 11:52:57.714] SETDATA devices.5.instances.0.commandClasses.114.data.interviewDone = Empty
[2015-02-06 11:52:57.715] SETDATA devices.5.instances.0.commandClasses.114.data.interviewCounter = Empty
[2015-02-06 11:52:57.717] SETDATA devices.5.instances.0.commandClasses.114.data.interviewDone = False
[2015-02-06 11:52:57.718] SETDATA devices.5.instances.0.commandClasses.114.data.interviewCounter = 10 (0x0000000a)
[2015-02-06 11:52:57.721] SETDATA devices.5.instances.0.commandClasses.114.data.vendorId = Empty
[2015-02-06 11:52:57.723] SETDATA devices.5.instances.0.commandClasses.114.data.vendor = Empty
[2015-02-06 11:52:57.724] SETDATA devices.5.instances.0.commandClasses.114.data.productId = Empty
[2015-02-06 11:52:57.725] SETDATA devices.5.instances.0.commandClasses.114.data.productType = Empty
[2015-02-06 11:52:57.727] Node 5:0 supports CC Association
[2015-02-06 11:52:57.728] SETDATA devices.5.instances.0.commandClasses.133.data = Empty
[2015-02-06 11:52:57.730] SETDATA devices.5.instances.0.commandClasses.133.data.supported = Empty
[2015-02-06 11:52:57.732] SETDATA devices.5.instances.0.commandClasses.133.data.supported = True
[2015-02-06 11:52:57.733] SETDATA devices.5.instances.0.commandClasses.133.data.version = Empty
[2015-02-06 11:52:57.732] Deleting device sensorMultilevel ZWayVDev_5:0:50:0
[2015-02-06 11:52:57.736] SETDATA devices.5.instances.0.commandClasses.133.data.version = 1 (0x00000001)
[2015-02-06 11:52:57.737] SETDATA devices.5.instances.0.commandClasses.133.data.security = Empty
[2015-02-06 11:52:57.740] Deleting device sensorMultilevel ZWayVDev_5:0:50:2
[2015-02-06 11:52:57.741] SETDATA devices.5.instances.0.commandClasses.133.data.security = False
[2015-02-06 11:52:57.743] SETDATA devices.5.instances.0.commandClasses.133.data.interviewDone = Empty
[2015-02-06 11:52:57.744] SETDATA devices.5.instances.0.commandClasses.133.data.interviewCounter = Empty
[2015-02-06 11:52:57.745] SETDATA devices.5.instances.0.commandClasses.133.data.interviewDone = False
[2015-02-06 11:52:57.748] Deleting device sensorMultilevel ZWayVDev_5:0:50:4
[2015-02-06 11:52:57.750] SETDATA devices.5.instances.0.commandClasses.133.data.interviewCounter = 10 (0x0000000a)
[2015-02-06 11:52:57.751] SETDATA devices.5.instances.0.commandClasses.133.data.groups = Empty
[2015-02-06 11:52:57.754] Deleting device sensorMultilevel ZWayVDev_5:0:50:5
[2015-02-06 11:52:57.755] Node 5:0 supports CC Version
[2015-02-06 11:52:57.756] SETDATA devices.5.instances.0.commandClasses.134.data = Empty
[2015-02-06 11:52:57.757] SETDATA devices.5.instances.0.commandClasses.134.data.supported = Empty
[2015-02-06 11:52:57.759] SETDATA devices.5.instances.0.commandClasses.134.data.supported = True
[2015-02-06 11:52:57.760] SETDATA devices.5.instances.0.commandClasses.134.data.version = Empty
[2015-02-06 11:52:57.761] SETDATA devices.5.instances.0.commandClasses.134.data.version = 1 (0x00000001)
[2015-02-06 11:52:57.763] SETDATA devices.5.instances.0.commandClasses.134.data.security = Empty
[2015-02-06 11:52:57.765] Deleting device sensorMultilevel ZWayVDev_5:0:50:6
[2015-02-06 11:52:57.766] SETDATA devices.5.instances.0.commandClasses.134.data.security = False
[2015-02-06 11:52:57.768] SETDATA devices.5.instances.0.commandClasses.134.data.interviewDone = Empty
[2015-02-06 11:52:57.769] SETDATA devices.5.instances.0.commandClasses.134.data.interviewCounter = Empty
[2015-02-06 11:52:57.770] SETDATA devices.5.instances.0.commandClasses.134.data.interviewDone = False
[2015-02-06 11:52:57.771] SETDATA devices.5.instances.0.commandClasses.134.data.interviewCounter = 10 (0x0000000a)
[2015-02-06 11:52:57.776] SETDATA devices.5.instances.0.commandClasses.134.data.ZWLib = Empty
[2015-02-06 11:52:57.777] SETDATA devices.5.instances.0.commandClasses.134.data.ZWProtocolMajor = Empty
[2015-02-06 11:52:57.778] SETDATA devices.5.instances.0.commandClasses.134.data.ZWProtocolMinor = Empty
[2015-02-06 11:52:57.779] SETDATA devices.5.instances.0.commandClasses.134.data.SDK = Empty
[2015-02-06 11:52:57.781] SETDATA devices.5.instances.0.commandClasses.134.data.applicationMajor = Empty
[2015-02-06 11:52:57.782] SETDATA devices.5.instances.0.commandClasses.134.data.applicationMinor = Empty
[2015-02-06 11:52:57.783] SETDATA devices.5.instances.0.commandClasses.134.data.hardwareVersion = Empty
[2015-02-06 11:52:57.785] SETDATA devices.5.instances.0.commandClasses.134.data.firmwareCount = Empty
[2015-02-06 11:52:57.787] SETDATA devices.5.data.lastSend = 249937 (0x0003d051)
[2015-02-06 11:52:57.788] SETDATA devices.5.data.isAwake = True
[2015-02-06 11:52:57.789] SETDATA devices.5.data.lastSend = 249837 (0x0003cfed)
[2015-02-06 11:52:57.790] SETDATA devices.5.data.lastNonceGet = 248837 (0x0003cc05)
[2015-02-06 11:52:57.791] Interview step 1
[2015-02-06 11:52:57.793] SETDATA devices.5.instances.0.commandClasses.134.data.interviewDone = False
[2015-02-06 11:52:57.794] SETDATA devices.5.instances.0.commandClasses.134.data.interviewCounter = 9 (0x00000009)
[2015-02-06 11:52:57.795] Node 5:0 CC Version Interview started
[2015-02-06 11:52:57.798] Adding job: Basic Version Get
[2015-02-06 11:52:57.799] Adding job: SwitchBinary Version Get
[2015-02-06 11:52:57.800] Adding job: SwitchAll Version Get
[2015-02-06 11:52:57.802] Adding job: Meter Version Get
[2015-02-06 11:52:57.803] Adding job: Configuration Version Get
[2015-02-06 11:52:57.805] Adding job: ManufacturerSpecific Version Get
[2015-02-06 11:52:57.806] Adding job: Association Version Get
[2015-02-06 11:52:57.807] Adding job: Version Version Get
[2015-02-06 11:52:57.808] Running wakeup handler for node 5
[2015-02-06 11:52:57.826] SENDING (cb 0x05): ( 01 04 00 4A 05 B4 )
[2015-02-06 11:52:57.838] RECEIVED ACK
[2015-02-06 11:52:57.839] Removing job: Add/re-include node to network
[2015-02-06 11:52:57.885] RECEIVED: ( 01 07 00 4A 77 06 05 00 C6 )
[2015-02-06 11:52:57.886] SENT ACK
[2015-02-06 11:52:57.887] Job 0x4a (Add/re-include node to network): Done
[2015-02-06 11:52:57.889] SETDATA controller.data.controllerState = 0 (0x00000000)
[2015-02-06 11:52:57.890] Job 0x4a (Add/re-include node to network): success
[2015-02-06 11:52:57.891] Removing job: Add/re-include node to network
[2015-02-06 11:52:57.904] SENDING (cb 0x78): ( 01 0A 00 13 05 03 86 13 20 25 78 08 )
[2015-02-06 11:52:57.916] RECEIVED ACK
[2015-02-06 11:52:57.927] RECEIVED: ( 01 04 01 13 01 E8 )
[2015-02-06 11:52:57.929] SENT ACK
[2015-02-06 11:52:57.930] Delivered to Z-Wave stack
[2015-02-06 11:52:59.502] RECEIVED: ( 01 14 00 04 00 05 0E 32 02 21 34 00 00 00 01 00 00 00 00 00 00 C0 )
[2015-02-06 11:52:59.504] SENT ACK
[2015-02-06 11:52:59.505] SETDATA devices.5.data.failureCount = 0 (0x00000000)
[2015-02-06 11:52:59.507] SETDATA devices.5.data.lastSend = 249984 (0x0003d080)
[2015-02-06 11:52:59.508] SETDATA devices.5.data.lastReceived = 0 (0x00000000)
[2015-02-06 11:52:59.509] Node 5:0 CC Meter: Unsupported Meter scale 2 received
[2015-02-06 11:52:59.511] Adding job: NoOperation
[2015-02-06 11:52:59.523] SENDING (cb 0x79): ( 01 08 00 13 05 01 00 25 79 BC )
[2015-02-06 11:52:59.535] RECEIVED ACK
[2015-02-06 11:52:59.546] RECEIVED: ( 01 04 01 13 01 E8 )
[2015-02-06 11:52:59.548] SENT ACK
[2015-02-06 11:52:59.549] Delivered to Z-Wave stack
[2015-02-06 11:53:04.587] RECEIVED: ( 01 05 00 13 78 01 90 )
[2015-02-06 11:53:04.666] SENT ACK
[2015-02-06 11:53:04.667] Job 0x13 (Basic Version Get): Not delivered to recipient
[2015-02-06 11:53:04.669] SETDATA devices.5.data.lastPacketInfo.delivered = False
[2015-02-06 11:53:04.670] SETDATA devices.5.data.lastPacketInfo.packetLength = 6 (0x00000006)
[2015-02-06 11:53:04.671] SETDATA devices.5.data.lastPacketInfo.deliveryTime = 6739 (0x00001a53)
[2015-02-06 11:53:04.672] SETDATA devices.5.data.lastPacketInfo = **********
[2015-02-06 11:53:05.166] RECEIVED: ( 01 05 00 13 79 01 91 )
[2015-02-06 11:53:05.167] SENT ACK
[2015-02-06 11:53:05.168] Job 0x13 (NoOperation): Not delivered to recipient
[2015-02-06 11:53:05.169] SETDATA devices.5.data.lastPacketInfo.delivered = False
[2015-02-06 11:53:05.170] SETDATA devices.5.data.lastPacketInfo.packetLength = 4 (0x00000004)
[2015-02-06 11:53:05.172] SETDATA devices.5.data.lastPacketInfo.deliveryTime = 5619 (0x000015f3)
[2015-02-06 11:53:05.174] SETDATA devices.5.data.lastPacketInfo = **********
[2015-02-06 11:53:05.186] SENDING (cb 0x7a): ( 01 08 00 13 05 01 00 25 7A BF )
[2015-02-06 11:53:05.198] RECEIVED ACK
[2015-02-06 11:53:05.209] RECEIVED: ( 01 04 01 13 01 E8 )
[2015-02-06 11:53:05.210] SENT ACK
[2015-02-06 11:53:05.212] Delivered to Z-Wave stack
[2015-02-06 11:53:10.828] RECEIVED: ( 01 05 00 13 7A 01 92 )
[2015-02-06 11:53:10.829] SENT ACK
[2015-02-06 11:53:10.832] Job 0x13 (NoOperation): Not delivered to recipient
[2015-02-06 11:53:10.834] SETDATA devices.5.data.lastPacketInfo.delivered = False
[2015-02-06 11:53:10.835] SETDATA devices.5.data.lastPacketInfo.packetLength = 4 (0x00000004)
[2015-02-06 11:53:10.836] SETDATA devices.5.data.lastPacketInfo.deliveryTime = 5620 (0x000015f4)
[2015-02-06 11:53:10.837] SETDATA devices.5.data.lastPacketInfo = **********
[2015-02-06 11:53:10.849] SENDING (cb 0x7b): ( 01 08 00 13 05 01 00 25 7B BE )
[2015-02-06 11:53:10.861] RECEIVED ACK
[2015-02-06 11:53:10.872] RECEIVED: ( 01 04 01 13 01 E8 )
[2015-02-06 11:53:10.874] SENT ACK
[2015-02-06 11:53:10.875] Delivered to Z-Wave stack
[2015-02-06 11:53:16.515] RECEIVED: ( 01 05 00 13 7B 01 93 )
[2015-02-06 11:53:16.516] SENT ACK
[2015-02-06 11:53:16.518] Job 0x13 (NoOperation): Not delivered to recipient
[2015-02-06 11:53:16.519] SETDATA devices.5.data.lastPacketInfo.delivered = False
[2015-02-06 11:53:16.520] SETDATA devices.5.data.lastPacketInfo.packetLength = 4 (0x00000004)
[2015-02-06 11:53:16.521] SETDATA devices.5.data.lastPacketInfo.deliveryTime = 5643 (0x0000160b)
[2015-02-06 11:53:16.523] SETDATA devices.5.data.lastPacketInfo = **********
[2015-02-06 11:53:16.524] Job 0x13 (NoOperation): Removing job due to too much retransmitions
[2015-02-06 11:53:16.526] SETDATA devices.5.data.failureCount = 1 (0x00000001)
[2015-02-06 11:53:16.527] SETDATA devices.5.data.lastSend = 251773 (0x0003d77d)
[2015-02-06 11:53:16.528] Job 0x13 (NoOperation): fail
[2015-02-06 11:53:16.530] Removing job: NoOperation
[2015-02-06 11:53:17.101] Executing script: zway.devices[5].instances[0].commandClasses[0x25].Set(0)
[2015-02-06 11:53:17.104] Adding job: SwitchBinary Set
[2015-02-06 11:53:17.106] Adding job: SwitchBinary Get
[2015-02-06 11:53:17.178] Job 0x4a: deleted from queue
[2015-02-06 11:53:17.215] Job 0x4a: deleted from queue
[2015-02-06 11:53:20.054] SENDING (cb 0x7c): ( 01 0A 00 13 05 03 86 13 20 25 7C 0C )
[2015-02-06 11:53:20.065] RECEIVED ACK
[2015-02-06 11:53:20.077] RECEIVED: ( 01 04 01 13 01 E8 )
[2015-02-06 11:53:20.078] SENT ACK
[2015-02-06 11:53:20.079] Delivered to Z-Wave stack
[2015-02-06 11:53:25.712] RECEIVED: ( 01 05 00 13 7C 01 94 )
[2015-02-06 11:53:25.714] SENT ACK
[2015-02-06 11:53:25.715] Job 0x13 (Basic Version Get): Not delivered to recipient
[2015-02-06 11:53:25.716] SETDATA devices.5.data.lastPacketInfo.delivered = False
[2015-02-06 11:53:25.718] SETDATA devices.5.data.lastPacketInfo.packetLength = 6 (0x00000006)
[2015-02-06 11:53:25.719] SETDATA devices.5.data.lastPacketInfo.deliveryTime = 5635 (0x00001603)
[2015-02-06 11:53:25.720] SETDATA devices.5.data.lastPacketInfo = **********
[2015-02-06 11:53:25.733] SENDING (cb 0x7d): ( 01 0A 00 13 05 03 86 13 20 25 7D 0D )
[2015-02-06 11:53:25.744] RECEIVED ACK
[2015-02-06 11:53:25.756] RECEIVED: ( 01 04 01 13 01 E8 )
[2015-02-06 11:53:25.757] SENT ACK
[2015-02-06 11:53:25.759] Delivered to Z-Wave stack
[2015-02-06 11:53:31.338] RECEIVED: ( 01 05 00 13 7D 01 95 )
[2015-02-06 11:53:31.339] SENT ACK
[2015-02-06 11:53:31.340] Job 0x13 (Basic Version Get): Not delivered to recipient
[2015-02-06 11:53:31.342] SETDATA devices.5.data.lastPacketInfo.delivered = False
[2015-02-06 11:53:31.343] SETDATA devices.5.data.lastPacketInfo.packetLength = 6 (0x00000006)
[2015-02-06 11:53:31.344] SETDATA devices.5.data.lastPacketInfo.deliveryTime = 5583 (0x000015cf)
[2015-02-06 11:53:31.346] SETDATA devices.5.data.lastPacketInfo = **********
[2015-02-06 11:53:31.347] Job 0x13 (Basic Version Get): Removing job due to too much retransmitions
[2015-02-06 11:53:31.348] SETDATA devices.5.data.failureCount = 2 (0x00000002)
[2015-02-06 11:53:31.350] SETDATA devices.5.data.lastSend = 253772 (0x0003df4c)
[2015-02-06 11:53:31.351] Job 0x13 (Basic Version Get): fail
[2015-02-06 11:53:31.353] Removing job: Basic Version Get
[2015-02-06 11:53:31.364] Notification: error (connection): Connection lost to Z-Wave device ID 5

[Mirar]

Seems related to the problems I have in this thread.

Do you per any chance have an Everspring AN158 around?

It might be a sign that the z-wave network frequency is jammed. I never fully reached a conclusion.

[alastairduncan]

I have 5 TKB TZ68 and 1 TKB TZ88B along with a couple of stellaz radiator valves and some fibaro universal binary sensors.

I've had a look at the queue and updated to the latest software I was running 1.7.2(no change in what is happening) The queue seemed to have a message to one of the radiator valves which was not shifting. I changed the batteries and restarted the rpi and this seems to have cleared that item. However, the other devices are still not responding to messages for switching them on and off. They all seem to end up with "not delivered to recipient" after a while they are removed from the queue with "Cancelling Job xxxxx" so the queue does not seem to be blocked. Messages are not being sent for some reason? If I send pings these seem to fail even when this is the only message in the queue. There are a few messages that are being delivered but these seem to be to the sensors.

Alastair

[Mirar]

Try turning off the switches and relays one by one and see if it gets better if one of them is powerless.

If that doesn't help you have the same issues I had earlier and my Everspring relays are not to blame and I guess I'll see the same problems again...

It seems like the problem is 100% inside the magic sigma hardware and the z-wave.me people are powerless. :p

[alastairduncan]

I've done a hard reset of the board. It will go into include mode but will not actually include any of the devices. It looks like board number 2 will have to be returned

[n0ahg]

Exclude them before trying to include them?

[SolarFlor]

In some case it is necessary to reset the devices as well

[alastairduncan]

I've tried to exclude and then include but had no luck. The switches don't have a reset and I didn't have to exclude them when I got the replacement board. I'll have anther go tomorrow evening before the board goes back.

[alastairduncan]

Things are looking up I've managed to get some of the devices included, none of them did this willingly and they are responding, they are a bit sluggish but I have found that they can take a little time to settle down when there has been some disruption particularly the battery devices. I still have no idea why it all went tits up in the first place and hope its not going to happen every 3 months

Thanks for the suggestions and help

[Mirar]

It sounds excessively much like the problems I have.

Did you try powering off your devices one by one (or half by half, for that matter) to see if it gets better?
The theory I have is that a relay was sending as much as it possibly could on the z-wave frequencies, thus jamming the razberry. (Why the razberry is more sensitive than the wall switch I never figured out. Worse antenna?)

(SInce unplugging the two specific relays, I've had zero issues with 'not delivered'. I've had one issue when the send queue got stuck, but that's reasonable for four weeks.)