HomeGenie Forum

Development => Bug reporting => Topic started by: pim555 on November 12, 2015, 10:29:04 PM

Title: Z-wave via razberry on RPI not working properly
Post by: pim555 on November 12, 2015, 10:29:04 PM

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 (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

Title: Re: Z-wave via razberry on RPI not working properly
Post by: pim555 on November 16, 2015, 09:47:24 PM

Does anyone have razberry working ok with HG?

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

Cheers
Pim

Title: Re: Z-wave via razberry on RPI not working properly
Post by: nolio on November 17, 2015, 06:43:58 AM

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

Title: Re: Z-wave via razberry on RPI not working properly
Post by: pim555 on November 17, 2015, 10:52:21 AM

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...

Title: Re: Z-wave via razberry on RPI not working properly
Post by: nolio on November 17, 2015, 08:32:15 PM

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 ....

Title: Re: Z-wave via razberry on RPI not working properly
Post by: pim555 on November 17, 2015, 09:50:47 PM

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

Title: Re: Z-wave via razberry on RPI not working properly
Post by: nolio on November 18, 2015, 08:39:48 PM

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

Title: Re: Z-wave via razberry on RPI not working properly
Post by: pim555 on November 18, 2015, 08:47:36 PM

/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

Title: Re: Z-wave via razberry on RPI not working properly
Post by: pim555 on November 18, 2015, 11:39:46 PM

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?

Title: Re: Z-wave via razberry on RPI not working properly
Post by: pim555 on November 25, 2015, 08:38:10 AM

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?

Title: Re: Z-wave via razberry on RPI not working properly
Post by: dani on November 25, 2015, 05:05:39 PM

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.

Title: Re: Z-wave via razberry on RPI not working properly
Post by: pim555 on November 25, 2015, 08:41:46 PM

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.

Title: Re: Z-wave via razberry on RPI not working properly
Post by: Gene on November 26, 2015, 10:49:51 AM

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.

Title: Re: Z-wave via razberry on RPI not working properly
Post by: gbremmer on December 30, 2015, 02:14:57 PM

Hi Gene,

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

Title: Re: Z-wave via razberry on RPI not working properly
Post by: gbremmer on December 30, 2015, 02:19:34 PM

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:

Title: Re: Z-wave via razberry on RPI not working properly
Post by: Maximo on January 07, 2016, 08:25:03 PM

I've noticed a similar issue in my log file. I'm running HG on Centos 7 with an Aeon Labs ZStick 2

2016-01-07 19:20:08.1365 Trace [[[ BEGIN REQUEST ]]]
2016-01-07 19:20:08.1378 Trace WaitAck
2016-01-07 19:20:08.1389 Trace Sending Message (Node=15, CallbackId=9E, Function=SendData, CommandClass=SwitchMultilevel)
2016-01-07 19:20:08.1408 Debug 01-09-00-13-0F-02-26-02-05-9E-57
2016-01-07 19:20:08.1458 Debug 06
2016-01-07 19:20:08.2471 Debug 01-04-01-13-01-E8
2016-01-07 19:20:08.2484 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2016-01-07 19:20:08.2494 Debug ZWaveMessage (Direction=Inbound, Header=SOF, NodeId=0, Type=Response, Function=SendData, CommandClass=NotSet)
2016-01-07 19:20:08.2513 Debug 06
2016-01-07 19:20:08.2523 Trace SendDataReady
2016-01-07 19:20:08.3539 Debug 01-05-00-13-9E-00-77
2016-01-07 19:20:08.3552 Debug ZWaveMessage (RawData=01-05-00-13-9E-00-77)
2016-01-07 19:20:08.3562 Debug ZWaveMessage (Direction=Inbound, Header=SOF, NodeId=0, Type=Request, Function=SendData, CommandClass=NotSet)
2016-01-07 19:20:08.3582 Debug 06
2016-01-07 19:20:08.3591 Trace Complete
2016-01-07 19:20:08.3601 Trace [[[ END REQUEST ]]] took 222 ms


Any ideas on what this NodeID=0 is?

Title: Re: Z-wave via razberry on RPI not working properly
Post by: gbremmer on January 17, 2016, 10:49:27 AM

I have the same:

[[ BEGIN REQUEST ]]]
2016-01-17 09:45:54.3758 Trace WaitAck
2016-01-17 09:45:54.3767 Trace Sending Message (Node=7, CallbackId=00, Function=RequestNodeInfo, CommandClass=NotSet)
2016-01-17 09:45:54.3767 Debug 01-04-00-60-07-9C
2016-01-17 09:45:54.4755 Debug 06-01-04-01-60-01-9B
2016-01-17 09:45:54.4763 Debug ZWaveMessage (RawData=01-04-01-60-01-9B)
2016-01-17 09:45:54.4767 Debug ZWaveMessage (Direction=Inbound, Header=SOF, NodeId=0, Type=Response, Function=RequestNodeInfo, CommandClass=NotSet)
2016-01-17 09:45:54.4767 Debug 06
2016-01-17 09:45:54.4779 Trace SendDataReady
2016-01-17 09:45:55.0794 Debug 01-06-00-49-81-00-00-31
2016-01-17 09:45:55.0802 Debug ZWaveMessage (RawData=01-06-00-49-81-00-00-31)
2016-01-17 09:45:55.0809 Debug ZWaveMessage (Direction=Inbound, Header=SOF, NodeId=0, Type=Request, Function=ApplicationUpdate, CommandClass=NotSet)
2016-01-17 09:45:55.0817 Debug 06
2016-01-17 09:45:55.0817 Trace Error
2016-01-17 09:45:55.0828 Trace [[[ END REQUEST ]]]

 I'm thinking about buying new equipment and get rid of HomeGenie. It hasn't been working for 3 weeks now.