Z-wave via razberry on RPI not working properly

[pim555]

Hi,

My config is a raspberry pi + razberry plugin board (z-wave controller). I also still have a CM15pro for X10 and a combination of X10 and Z-wave switches as well as a few z-wave sensors.

I am struggling to get z-wave to work properly. Especially inclusion/exclusion of devices is troublesome. I used to have it working on r493 at one time, then moved to r500 but could not get it to work anymore.

As I started doubting my z-wave devices, I installed the default z-wave control stack called z-way:
http://razberry.z-wave.me/index.php?id=10

This stack offers detailed insight and control of the z-wave network and devices. With this stack installed on RPI, I have no issues anymore in inclusion and exclusion of the multisensor modules that I cannot get installed in HG.

One of the things I see in z-way is that device interview, when including this sensor, takes a lot of time. Maybe that is one of the reasons why it is not properly installing?

Any help is welcome as this is really an issue for me since I would like to move over to z-wave.

Regards
Pim

[pim555]

Does anyone have razberry working ok with HG?

Or is it working with Z-wave USB sticks only?

Cheers
Pim

[nolio]

Hi,
When you "get" and your device works on battery, you have to wake up the device. Usually press 3 times the inclusion button, but rake a look at the spec sheet to be sure...
Did you already try that ?
Bye

[pim555]

Yeah, tried all that and in z-way (razberry controller software for RPI) that works perfectly. It is just that in HG it does not seem to work reliably. I had it working some time ago but cannot get it to recognize again. And I did make sure the device is excluded from any other controller.

Looks to me like the protocol handler in HG is not fully compliant or so? The amount of detail provided in HG UI is also very limited so it is difficult to see what is going on. In z-way you can perfectly see the status of devices, even when the interview process is ongoing...

[nolio]

Did you try to enable log ? (configure->maintenance->system options->log file->enable and take a look in "/usr/local/bin/homegenie/log/homegenie.log")
Perhaps a log that permit to identify what works wrong ....

[pim555]

Thanks

I see in the log that the add request is timing out (see below). But I also see errors on zwave initialization

"2015-11-17 21:23:37.4820 Error HomeAutomation.HomeGenie   InterfaceControl   Object reference not set to an instance of an object   Exception.StackTrace     at ZWaveLib.CommandClasses.Basic.Get (ZWaveLib.ZWaveNode node) [0x00000] in <filename unknown>:0 "         
  at MIG.Interfaces.HomeAutomation.ZWave.InterfaceControl (MIG.MigInterfaceCommand request) [0x00000] in <filename unknown>:0          
  at HomeGenie.Service.HomeGenieService.InterfaceControl (MIG.MigInterfaceCommand cmd) [0x00000] in <filename unknown>:0          
"2015-11-17 21:23:37.4847 Error HomeAutomation.HomeGenie   InterfaceControl   Object reference not set to an instance of an object   Exception.StackTrace     at ZWaveLib.CommandClasses.Basic.Get (ZWaveLib.ZWaveNode node) [0x00000] in <filename unknown>:0 "         
  at MIG.Interfaces.HomeAutomation.ZWave.InterfaceControl (MIG.MigInterfaceCommand request) [0x00000] in <filename unknown>:0          
  at HomeGenie.Service.HomeGenieService.InterfaceControl (MIG.MigInterfaceCommand cmd) [0x00000] in <filename unknown>:0          
"2015-11-17 21:23:37.4873 Error HomeAutomation.HomeGenie   InterfaceControl   Object reference not set to an instance of an object   Exception.StackTrace     at ZWaveLib.CommandClasses.Basic.Get (ZWaveLib.ZWaveNode node) [0x00000] in <filename unknown>:0 "         
  at MIG.Interfaces.HomeAutomation.ZWave.InterfaceControl (MIG.MigInterfaceCommand request) [0x00000] in <filename unknown>:0          
  at HomeGenie.Service.HomeGenieService.InterfaceControl (MIG.MigInterfaceCommand cmd) [0x00000] in <filename unknown>:0          


Add request timing out:

2015-11-17 21:41:51.3249 Warn Message timeout (Node=0
2015-11-17 21:41:51.3249 Trace [[[ END REQUEST ]]] took 10001 ms
2015-11-17 21:41:51.3249 Warn Could not deliver message to Node 0 (CallbackId=05
2015-11-17 21:41:51.3261 Trace [[[ BEGIN REQUEST ]]]
2015-11-17 21:41:51.3261 Trace WaitAck
2015-11-17 21:41:51.3269 Trace Sending Message (Node=0
2015-11-17 21:41:51.3284 Debug 01-05-00-4A-81-05-34
2015-11-17 21:41:51.3583 Debug 06-01-07-00-4A-05-07-00-00-B0
2015-11-17 21:41:51.3591 Debug 06
2015-11-17 21:41:53.6216 Debug 01-1B-00-4A-05-02-02-14-04-07-01-86-72-5E-59-85-73-71-84-80-30-31-70-98-7A-EF-5A-82-69
2015-11-17 21:41:53.6226 Debug 06
2015-11-17 21:41:53.6737 Debug 01-1B-00-4A-05-03-02-14-04-07-01-86-72-5E-59-85-73-71-84-80-30-31-70-98-7A-EF-5A-82-68
2015-11-17 21:41:53.6745 Debug 06
"2015-11-17 21:41:53.8025 Info WebServiceGateway   192.168.1.103   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/"
2015-11-17 21:41:53.9260 Debug 01-07-00-4A-05-05-02-00-B0
2015-11-17 21:41:53.9268 Debug 06
2015-11-17 21:41:58.9534 Debug 01-1A-00-49-84-02-14-04-07-01-86-72-5E-59-85-73-71-84-80-30-31-70-98-7A-EF-5A-82-E8
2015-11-17 21:41:58.9544 Debug 06
2015-11-17 21:41:59.3020 Debug 01-1A-00-49-84-02-14-04-07-01-86-72-5E-59-85-73-71-84-80-30-31-70-98-7A-EF-5A-82-E8
2015-11-17 21:41:59.3029 Debug 06
2015-11-17 21:41:59.3029 Warn Repeated message discarded.
2015-11-17 21:41:59.7050 Debug 01-1A-00-49-84-02-14-04-07-01-86-72-5E-59-85-73-71-84-80-30-31-70-98-7A-EF-5A-82-E8
2015-11-17 21:41:59.7059 Debug 06
2015-11-17 21:41:59.7059 Warn Repeated message discarded.
2015-11-17 21:42:00.9560 Trace BEGIN
2015-11-17 21:42:01.3282 Trace Error
2015-11-17 21:42:01.3288 Warn Message timeout (Node=0
2015-11-17 21:42:01.3288 Trace [[[ END REQUEST ]]] took 10002 ms
2015-11-17 21:42:01.3288 Warn Could not deliver message to Node 0 (CallbackId=05
2015-11-17 21:42:01.3300 Trace [[[ BEGIN REQUEST ]]]
2015-11-17 21:42:01.3300 Trace WaitAck
2015-11-17 21:42:01.3300 Trace Sending Message (Node=0
2015-11-17 21:42:01.3314 Debug 01-05-00-4A-81-05-34
2015-11-17 21:42:01.3662 Debug 06-01-07-00-4A-05-01-00-00-B6
2015-11-17 21:42:01.3671 Debug 06
"2015-11-17 21:42:03.8010 Info WebServiceGateway   192.168.1.103   HTTP   GET   200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/"
2015-11-17 21:42:04.7810 Debug 01-1B-00-4A-05-02-02-14-04-07-01-86-72-5E-59-85-73-71-84-80-30-31-70-98-7A-EF-5A-82-69
2015-11-17 21:42:04.7821 Debug 06
2015-11-17 21:42:04.8328 Debug 01-1B-00-4A-05-03-02-14-04-07-01-86-72-5E-59-85-73-71-84-80-30-31-70-98-7A-EF-5A-82-68
2015-11-17 21:42:04.8337 Debug 06
2015-11-17 21:42:05.1353 Debug 01-07-00-4A-05-05-02-00-B0
2015-11-17 21:42:05.1365 Debug 06
"2015-11-17 21:42:10.9598 Info WebServiceGateway   192.168.1.103   HTTP   GET   200 /api/HomeAutomation.ZWave/1/Controller.NodeAdd/"
2015-11-17 21:42:10.9561 Trace END
2015-11-17 21:42:11.3311 Trace Error
2015-11-17 21:42:11.3318 Warn Message timeout (Node=0
2015-11-17 21:42:11.3318 Trace [[[ END REQUEST ]]] took 10002 ms
2015-11-17 21:42:11.3318 Warn Delivery of message to Node 0 failed (CallbackId=05).
2015-11-17 21:42:11.4333 Trace [[[ BEGIN REQUEST ]]]
2015-11-17 21:42:11.4338 Trace WaitAck
2015-11-17 21:42:11.4338 Trace Sending Message (Node=0
2015-11-17 21:42:11.4350 Debug 01-05-00-4A-05-06-B3
2015-11-17 21:42:11.4638 Debug 06
2015-11-17 21:42:11.5145 Debug 01-07-00-4A-06-06-02-00-B0
2015-11-17 21:42:11.5161 Debug 06

[nolio]

Ok.
And what did you configure in "configure"->"interfaces"->"zwave"->"control port" ?

[pim555]

/dev/ttyAMA0.


I see that Gene has made some changes to Z-wave in r501 so am going to give that a try.

Regards
Pim

[pim555]

Did some further testing with r501.

From a clean install I can add the sensor device although it still times out. If I restore a backup, all z-wave node information is restored while the controller has been reset in between. So I guess this will not work. However, if I then hard reset the controller, all node information is gone (as expected) but I am not able to add a device anymore. It does not detect the device (pls note that I did exclude before to make sure it is not in any network before adding).

Does anyone have any hints as to what might be going wrong here?

[pim555]

Migrated to r503.

Below backup issue caused the controller to be in a state that is not correct so before upgrading to r503 I created a backup with z-wave disabled. After uprade I restore this backup and enable z-wave again. In that case I can properly add devices/sensors again.

However, I still see non correct behavior with sensors (Everspring SP103 and Aeonlabs multi sensor). Still get timeouts on read of parameters (although device ID is read properly). Also, apparantly writes of parameters do not seem to work. Is there a way to add some feedback in the UI that the write has succesfully completed?

[dani]

Since some revisions, if the node doesn't response to a command you will receive in the UI an Event Error timeout for the node.

[pim555]

I know...but

The funny thing is that the nodes do properly respond if I use a different controller (Z-way). However, with HG I continuously get timeouts. This leads me to believe that the Z-wave protocol is not properly supported/implemented in HG.

[Gene]

Hi pim,

can you please post the complete log since the beginning? ZWaveLib is trying to get info from a node "0" and that is obviously a bug. There's no such node 0. I see many of you having troubles with RaZberry and I would like to fix them.

g.

[gbremmer]

Hi Gene,

I'm also having issues with RaZberry. See my attached log file.

[gbremmer]

In addition to previous post, It's also acting strange. I get constant time-out errors and discovery doens't seem to reconize any host: