Page 1 of 1

Delay in action group executing actions

PostPosted: Mon Jan 28, 2019 3:42 pm
by siclark
I have set up some Fibaro dimmers using scenes and am impressed, however I have noticed some delays in execution. I decided to check the logs tonight, and found that the fibaro modules are sending the scenes to indigo immediately, and the trigger is responding and activating the action group, however on the scene to turn all my kitchen lights on, there was an 8 second delay between the Action group being triggered and the first Z-Wave sent message.

I havent taken anything out of the log here, so I dont think anything else was going on at the time, so is this normal? I can understand the zwave commands taking a few seconds to action if there is a network congestion. Is this an issue with the mac not having resource to send the commands?

Thankfully most activations of the lights in the kitchen are on timers and motion, and I havent explained this new functionality to my wife yet, but I Dont think she would understand such a long delay. Any pointers appreciated.

2019-01-28 21:30:33.727 Z-Wave received "Kitchen Table Spots" scene 14
2019-01-28 21:30:33.728 Trigger Kitchen Table Spots Switch 1 2xClick Scn 14
2019-01-28 21:30:33.728 Action Group Kitchen Lights Off
2019-01-28 21:30:33.743 Z-Wave received "Kitchen LED Strip" power load to 34.8 W
2019-01-28 21:30:33.882 Z-Wave sent "Kitchen Hob Spots" off
2019-01-28 21:30:34.084 Z-Wave sent "Kitchen Table Spots" off
2019-01-28 21:30:34.084 Trigger KitchenLightStatusVariableOff
2019-01-28 21:30:34.131 Z-Wave sent "Kitchen Island Pendants" off
2019-01-28 21:30:34.268 Z-Wave sent "Kitchen LED Strip" off

2019-01-28 21:30:40.295 Z-Wave received "Kitchen Hob Spots" scene 14
2019-01-28 21:30:40.296 Trigger Kitchen Hob Spots Switch 1 2xClick Scn 14
2019-01-28 21:30:40.296 Action Group Kitchen Lights On
2019-01-28 21:30:48.057 Z-Wave sent "Kitchen Hob Spots" on

2019-01-28 21:30:48.183 Z-Wave sent "Kitchen Table Spots" on
2019-01-28 21:30:48.184 Trigger KitchenLightStatusVariableOn
2019-01-28 21:30:48.270 Z-Wave sent "Kitchen LED Strip" on
2019-01-28 21:30:48.363 Z-Wave sent "Kitchen Island Pendants" on

Re: Delay in action group executing actions

PostPosted: Mon Jan 28, 2019 4:15 pm
by jay (support)
Is there a delay associated with the Action Group execution (sometimes those can accidentally get added or forgotten).

Re: Delay in action group executing actions

PostPosted: Mon Jan 28, 2019 4:27 pm
by siclark
No. And it appears to be random.
First two action groups, Kitchen Lights Off, then On, all is triggered and done within under a second.

Then the Off at 22:18:19, has a 4 second delay in the sending of the Z-Wave command.

Lastly, the On at 22:18:27 takes so long to respond after the Kitchen Hob Spots command is sent, you can see I trigger the scene again at 22:18:35. The last Zwave command is sent 6 seconds after that.

The Action group is triggered and actioned within 0.01 seconds which is impressive. But for some reason sending the Zwave commands sometimes has a big lag.

2019-01-28 22:18:04.717 Z-Wave received "Kitchen Table Spots" scene 14
2019-01-28 22:18:04.772 Trigger Kitchen Table Spots Switch 1 2xClick Scn 14
2019-01-28 22:18:04.773 Action Group Kitchen Lights Off
2019-01-28 22:18:04.859 Z-Wave sent "Kitchen Hob Spots" off

2019-01-28 22:18:04.916 Z-Wave sent "Kitchen Table Spots" off
2019-01-28 22:18:04.916 Trigger KitchenLightStatusVariableOff
2019-01-28 22:18:04.964 Z-Wave sent "Kitchen Island Pendants" off
2019-01-28 22:18:05.036 Z-Wave sent "Kitchen LED Strip" off

2019-01-28 22:18:09.377 Z-Wave received "Kitchen Hob Spots" scene 14
2019-01-28 22:18:09.377 Trigger Kitchen Hob Spots Switch 1 2xClick Scn 14
2019-01-28 22:18:09.378 Action Group Kitchen Lights On
2019-01-28 22:18:09.780 Z-Wave sent "Kitchen Hob Spots" on

2019-01-28 22:18:09.822 Z-Wave sent "Kitchen Table Spots" on
2019-01-28 22:18:09.823 Trigger KitchenLightStatusVariableOn
2019-01-28 22:18:09.868 Z-Wave sent "Kitchen LED Strip" on
2019-01-28 22:18:09.949 Z-Wave sent "Kitchen Island Pendants" on

2019-01-28 22:18:19.726 Z-Wave received "Kitchen Table Spots" scene 14
2019-01-28 22:18:19.726 Trigger Kitchen Table Spots Switch 1 2xClick Scn 14
2019-01-28 22:18:19.726 Action Group Kitchen Lights Off
2019-01-28 22:18:23.636 Z-Wave sent "Kitchen Hob Spots" off

2019-01-28 22:18:23.694 Z-Wave sent "Kitchen Table Spots" off
2019-01-28 22:18:23.695 Trigger KitchenLightStatusVariableOff
2019-01-28 22:18:23.745 Z-Wave sent "Kitchen Island Pendants" off
2019-01-28 22:18:23.873 Z-Wave sent "Kitchen LED Strip" off
2019-01-28 22:18:27.838 Z-Wave received "Kitchen Hob Spots" scene 14

2019-01-28 22:18:27.838 Trigger Kitchen Hob Spots Switch 1 2xClick Scn 14
2019-01-28 22:18:27.839 Action Group Kitchen Lights On
2019-01-28 22:18:31.360 Z-Wave sent "Kitchen Hob Spots" on
2019-01-28 22:18:35.945 Z-Wave received "Kitchen Hob Spots" scene 14
2019-01-28 22:18:35.946 Trigger Kitchen Hob Spots Switch 1 2xClick Scn 14
2019-01-28 22:18:35.946 Action Group Kitchen Lights On
2019-01-28 22:18:36.031 Z-Wave sent "Kitchen Table Spots" on
2019-01-28 22:18:36.032 Trigger KitchenLightStatusVariableOn
2019-01-28 22:18:36.129 Z-Wave sent "Kitchen LED Strip" on
2019-01-28 22:18:36.160 Z-Wave sent "Kitchen Island Pendants" on
2019-01-28 22:18:36.179 Z-Wave sent "Kitchen Hob Spots" on
2019-01-28 22:18:40.115 Z-Wave sent "Kitchen Table Spots" on
2019-01-28 22:18:41.498 Z-Wave sent "Kitchen LED Strip" on
2019-01-28 22:18:41.538 Z-Wave sent "Kitchen Island Pendants" on

Re: Delay in action group executing actions

PostPosted: Mon Jan 28, 2019 6:35 pm
by jay (support)
Are the devices in the OFF group and the ON group identical?

Also, when you notice the delay, have you noticed what the green progress bar in the lower left corner of the Home Window looks like? Are there lots of commands queued up (making the bar green)?

Re: Delay in action group executing actions

PostPosted: Tue Jan 29, 2019 1:00 am
by siclark
Yes they are identical.

I'm not sure, I shouldn't have that much going on on my zwave network especially once I worked out how to stop constant power updates from a qubino relay.
I'll get a laptop and VNC to the Mac to check the status of the green bar whilst I do the switches.
Is it more likely zwave network congestion than Mac issues?
I do have a couple of cheap sockets that don't report to the log their energy messages, I'll have to turn those back to log in case they are flooding the network.
Cheers.


Sent from my iPhone using Tapatalk

Re: Delay in action group executing actions

PostPosted: Tue Jan 29, 2019 8:39 am
by matt (support)
siclark wrote:
Is it more likely zwave network congestion than Mac issues?

Yes. You can turn on Indigo's Z-Wave debug logging via the Interfaces->Z-Wave->Configure... menu item and you'll get a better idea of what is occurring at the Z-Wave level. Copy/paste what you see in the Event Log after another delay occurs into a reply for us.

Re: Delay in action group executing actions

PostPosted: Tue Jan 29, 2019 9:41 am
by autolog
I think it could possibly be the Spirit Thermostats with FliRS causing the delays as Indigo waits for the response. I have noticed this since adding 12 Spirits in all. Some respond straight away but now and then one of them takes a bit longer to respond and any other commands done at the same time queue up. So you can turn on a Z-Wave switch and it might take a number of seconds to respond.

Maybe Indigo should bail if the response is longer than a second and queue any further commands for that device but action any others. :)

Re: Delay in action group executing actions

PostPosted: Tue Jan 29, 2019 8:42 pm
by matt (support)
Interesting. Indigo doesn't have a priority queue for outbound commands, but if this is the problem (FLIRS command latency) then I'll add it to the request list.