Delay when changing lights

  • 65 Views
  • Last Post 17 January 2013
skalman1977 posted this 29 December 2012

Hi,

i have a Aeon Labs z-stick v.2 and 5 Fibaro modules. 4 of them are dimmers and one is a switch.

The problem i have is that it sometimes takes up to 30 seconds for InControl to change (turn on/off or dim) the lights. I have earlier used zVirtualscene, but swapped to InControl 2 days ago. It seems that InControl have more features (such as better trigger features etc.).

I have also seen issues where InControl haven't change status of devices, if i have changed several devices within short period. This behavior also occurs if i use scenes with multiple devices.

Would appreciate if someone could help me. I can see in the log file that InControl have problem to poll ID6 which is one of my dimmers. Could it be a routing problem? (which i didn't have with zVirtualscene). Could that also result in long respons times for the other devices? I am able to turn on/off/dim ID6, so InControl do have connectionto the device.

Order By: Standard | Newest | Votes
Ryan-Scott posted this 29 December 2012

If a device can't be polled, it could certainly cause other devices to delay. If the USB stick is in the process of waiting for a response from a device and the device takes 30 seconds to respond, then all commands sent during that time will wait in a line until the USB stick is free again.

You may try to beef up your network strength by adding other devices. You could also try to turn off polling for your devices until you find the one that is causing the most delay.

Additionally, try switching to the beta z-wave controller found in the tools/options menu to see if you get a faster response time with your devices.

skalman1977 posted this 29 December 2012

The thing is that all devices are within 15 meters from the usb stick. I have tested the beta driver, but it didn't help.

I will try and move the stick.

Ryan-Scott posted this 29 December 2012

Before moving your stick, could you disable polling on each device and see if the delay goes away?

skalman1977 posted this 29 December 2012

Even if i disable polling on all devices am i still experiencing delays an sometimes problem to turn on a device.

below is part of my logs:

2012-12-29 22:34:09: Error polling device level. NodeId 5 - ControlThink.ZWave.CommandTimeoutException
The command timed out before a response was received.

vid ControlThink.ZWave.Devices.BinarySwitch.BasicGet(l instance, Boolean isBackgroundRequest)
vid ControlThink.ZWave.Devices.ZWaveDevice.GetLevel(Boolean isBackgroundRequest)
vid ControlThink.ZWave.Devices.ZWaveDevice.GetLevel()
vid ControlThink.ZWave.Devices.ZWaveDevice.get
Level()
vid MLS.HA.DeviceController.Controllers.HaZWaveController.pollDevices() i Z:\work\mls\MLS.ZWave.Controller\MLS.HA.DeviceController\Controllers\HaZWaveController.cs:rad 276

2012-12-29 22:36:42: Error during ZDevice setLevel - ControlThink.ZWave.CommandNotStartedTimeoutException
The command timed out before it could be issued.

vid ControlThink.ZWave.ZWaveController.a(Byte A0, a A1, l A2, Byte A3, Byte[] A4)
vid ControlThink.ZWave.Devices.ZWaveDevice.Basic
Set(l instance, Byte value)
vid ControlThink.ZWave.Devices.ZWaveDevice.BasicSet(Byte value)
vid ControlThink.ZWave.Devices.ZWaveDevice.SetLevel(Byte value)
vid ControlThink.ZWave.Devices.ZWaveDevice.set
Level(Byte Value)
vid MLS.HA.DeviceController.Controllers.HaZWaveController.<>cDisplayClass7.b6() i Z:\work\mls\MLS.ZWave.Controller\MLS.HA.DeviceController\Controllers\HaZWaveController.cs:rad 788

2012-12-29 22:39:43: ZWave Device Count: 6
2012-12-29 22:40:13: ZWave Device Count: 6
2012-12-29 22:40:43: ZWave Device Count: 6
2012-12-29 22:41:13: ZWave Device Count: 6
2012-12-29 22:41:43: ZWave Device Count: 6
2012-12-29 22:41:51: Error during ZDevice setLevel - ControlThink.ZWave.CommandTimeoutException
The command timed out before a response was received.

vid ControlThink.ZWave.ZWaveController.a(Byte A0, a A1, l A2, Byte A3, Byte[] A4)
vid ControlThink.ZWave.Devices.ZWaveDevice.Basic
Set(l instance, Byte value)
vid ControlThink.ZWave.Devices.ZWaveDevice.BasicSet(Byte value)
vid ControlThink.ZWave.Devices.ZWaveDevice.SetLevel(Byte value)
vid ControlThink.ZWave.Devices.ZWaveDevice.set
Level(Byte Value)
vid MLS.HA.DeviceController.Controllers.HaZWaveController.<>cDisplayClass7.b6() i Z:\work\mls\MLS.ZWave.Controller\MLS.HA.DeviceController\Controllers\HaZWaveController.cs:rad 788
2012-12-29 22:42:13: ZWave Device Count: 6
2012-12-29 22:42:43: ZWave Device Count: 6

Is above indicating communication problems? Is it possible to do a routing update thru InControl if i move the stick?

Ryan-Scott posted this 29 December 2012

What USB stick do you own?

The timeout could be related to distance, but it could also be related to your USB stick being inaccessible or in-use by another program since only one program can use it at a time.

skalman1977 posted this 29 December 2012

Its a Aeon Labs Z-stick v2 EUR with latest driver and firmware.

I will try move the stick. It is now located behind my media bench. Will try tomorrow to move it to an open area.

My last question. Is it possible to do a network update / routing update after i have moved it?

skalman1977 posted this 30 December 2012

I have now tested several different locations and drivers. There is still a delays / errors.

I have tested Z-tools (beta) an can see the same behavior in that program. But when using a program that are using the Open Z-wave dll everything works perfect (despite of where i locate the Z-stick).

Is there any timeout values that could be changed?

I tried to "optimize" with z-tool, but got "node x not responding".

Please help!!! Would really like to start using InControl instead of zVirtualscene.

Ryan-Scott posted this 31 December 2012

I have now tested several different locations and drivers. There is still a delays / errors.

I have tested Z-tools (beta) an can see the same behavior in that program. But when using a program that are using the Open Z-wave dll everything works perfect (despite of where i locate the Z-stick).

Is there any timeout values that could be changed?

I tried to "optimize" with z-tool, but got "node x not responding".

Please help!!! Would really like to start using InControl instead of zVirtualscene.


Is it all devices that delay or is it just a single device that's delaying? Does it delay every time? How long does it take before the device responds to the command? How many total devices do you have?

skalman1977 posted this 01 January 2013

Hi,

It's all devices that are affected randomly. But the delay doesn't happeneds every time. I would say it's one of three times i experience a (approx) 30 sec. delay. Scenes work half of the times, the other half doesn't all devices turn on.

I have a total of 5 switches / dimmers. Please read first post for details.

I have today tested to change the COM port speed, since i read on another forum that the z-stick 2 should run at 115200. This however didn't do any changes to the delay.

Ryan-Scott posted this 01 January 2013

Hi,

It's all devices that are affected randomly. But the delay doesn't happeneds every time. I would say it's one of three times i experience a (approx) 30 sec. delay. Scenes work half of the times, the other half doesn't all devices turn on.

I have a total of 5 switches / dimmers. Please read first post for details.

I have today tested to change the COM port speed, since i read on another forum that the z-stick 2 should run at 115200. This however didn't do any changes to the delay.


All I can think is that you've got one or more devices that aren't routing properly and it's causing things to slow down globally. You might consider re-building your network by excluding all devices, resetting your USB stick then re-including each device again.

Axial-User posted this 04 January 2013

The easiest way to figure this out is to remove all the switches from your network except one. Test with just a single one. It's very easy with the Aeon Stick to just walk around and remove them. Just test with one switch, see what you get with that.

-N

Axial-User posted this 04 January 2013

I had the same problem after upgrading to 2.19. I unistall everything, even deleted the zwave folder and reinstalled 2.17 everything worked fine again.

skalman1977 posted this 04 January 2013

But where can i find the 2.17 version? I have today excluded devices an can get this version to work, as long as i don´t have more than 2 dimmers added to the z-wave network...

When i only install "curren" 2.110.4702.12233 without upgrading, cant InControl find my Z-stick...

Ryan-Scott posted this 04 January 2013

But where can i find the 2.17 version? I have today excluded devices an can get this version to work, as long as i don´t have more than 2 dimmers added to the z-wave network...

When i only install "curren" 2.110.4702.12233 without upgrading, cant InControl find my Z-stick...


You might try a beta version linked in the Announcement section of the forums.

skalman1977 posted this 05 January 2013

I have now tested all versions from 2.117 - 2.124. All of them with same result :(.

I would say it must be ControlThink.ZWave.dll that doesn´t work as it should...

Would it be hard to create a version base on the OpenZWaveDotNet.dll? This would help me, as i don´t have this issue when using programs that are based on that dll... (As this works, do i belive it couldn´t be my Z-wave network that is failing!?!)

The last thing i will test this afternoon is to reset my USB stick, after i have excluded all devices.

skalman1977 posted this 05 January 2013

I have now reset the stick, and seems that everything work as it should. I have included 4 of 5 switches. I will wait with the last device a couple of days to see that it's working as it should. The last device will need routing, so to rule out routing problem have i waited with that one.

Thanks for all the help! I will post the result within a couple of days.

skalman1977 posted this 06 January 2013

I was to fast in my previous post. I noticed this morning that one of my devices hadn´t turned off at sunrise. When looking in the log i saw a new error message.. I renamed the device yesterday, but the device had now renamed itself to standardswitch.

example from the log is found below:

2013-01-05 17:37:14: Device 2 delayed for 30,0040422. Logging error.
2013-01-05 17:41:44: Device 2 delayed for 30,0050423. Logging error.
2013-01-05 17:48:07: Checking Command queue processor. Should be Running: True. Queued commands: 0. Security Manager Busy: False
2013-01-05 18:18:07: Checking Command queue processor. Should be Running: True. Queued commands: 0. Security Manager Busy: False

I was also unable to manually control the failing device. Rename wasn´t working eighter. Even though the log is saying there is a 30,... delay, there´s no delay what i can notice when turning on/off the lights.

What i did was to rename the database, so that Incontrol would create a new, since i believed it to be a database issue. Unfortunatly was the old issue back when the new database was created... So i switched back to the failed database, where i cannot control one of my switches. But all the other devices are working without any noticable delays...

I have also disabled UAC in windows, to rule out that this is causing the delays... This didn´t result in anything.

Ryan-Scott posted this 07 January 2013

I was to fast in my previous post. I noticed this morning that one of my devices hadn´t turned off at sunrise. When looking in the log i saw a new error message.. I renamed the device yesterday, but the device had now renamed itself to standardswitch.

example from the log is found below:

2013-01-05 17:37:14: Device 2 delayed for 30,0040422. Logging error.
2013-01-05 17:41:44: Device 2 delayed for 30,0050423. Logging error.
2013-01-05 17:48:07: Checking Command queue processor. Should be Running: True. Queued commands: 0. Security Manager Busy: False
2013-01-05 18:18:07: Checking Command queue processor. Should be Running: True. Queued commands: 0. Security Manager Busy: False

I was also unable to manually control the failing device. Rename wasn´t working eighter. Even though the log is saying there is a 30,... delay, there´s no delay what i can notice when turning on/off the lights.

What i did was to rename the database, so that Incontrol would create a new, since i believed it to be a database issue. Unfortunatly was the old issue back when the new database was created... So i switched back to the failed database, where i cannot control one of my switches. But all the other devices are working without any noticable delays...


Just to be clear on your issue... your node id #2 in the new database is not responding to commands? Is the one that changed back to "StandardSwitch" also showing a "2" at the bottom of the detail panel when you click on it inside the InControl console?

You also mention:


...Even though the log is saying there is a 30,... delay, there´s no delay what i can notice when turning on/off the lights....



...So i switched back to the failed database, where i cannot control one of my switches....


I'm a little confused... you mention not being able to control it, yet you can control it without delay? Can you clarify which device by referring to the node id of the device? Also, it might be useful if you could upload your log entire logfile to somewhere like http://www.speedyshare.com/ and then provide me a link.

skalman1977 posted this 09 January 2013

I will try to explain.

When i first installed Incontrol 2.119 i had issues where i had delays and the lights sometimes didn't even turn on/off. This was my first problem.

I then excluded all devices and reset the USB stick. I have now added 4 (out of 5) devices again and installed a fresh install of 2.119. I didn't add the last one, as it need repeating to work (to far away from controller). When looking at the logs after this fresh install, the log looked different. I didn't didn't get any"Error polling device" or "Error set_level". But now i could see "Device x (often 2, but have showed on other devices as well) delayed for 30,0040422. Logging error". Although the message occur, i cannot notice any big delays (only 2-5 second). Yesterday i had my first missed "turn off". So it doesn't work 100%, but much better than before.

I tried to update to 2.124, but the error message is the same.

What i did then was to backup and removed the existing Db, so that Incontrol would create a new. This time the "Error polling device.." error message occured again. When this happends, i can notice a big delay and sometimes / often missed turn on / off.

I therefore decided to go back to the backuped Db. This has working OK the last days, but i believe that there is something wrong with that DB, since the log looks differend and it says "Loggin error in the end of the "delayed for 30...." message. Could you look at this Db to see what is wrong with it?

I tried to install Incontrol on my work computer yesterday, to see if it could be something wrong with my Windows installation. But unfortunatly did i experience the same delays on that one. So didn't seem to be related to the computer i'm using.

I should say that both my home and work computer have Win7 x64 installed, if that could result in this type of problem?

Ryan-Scott posted this 12 January 2013


What i did then was to backup and removed the existing Db, so that Incontrol would create a new. This time the "Error polling device.." error message occured again. When this happends, i can notice a big delay and sometimes / often missed turn on / off.

I therefore decided to go back to the backuped Db. This has working OK the last days, but i believe that there is something wrong with that DB, since the log looks differend and it says "Loggin error in the end of the "delayed for 30...." message. Could you look at this Db to see what is wrong with it?


The only thing I can see that might be different in behavior is the z-wave engine you used between db backups. Perhaps the first time you were using the beta z-wave controller, but after the backup you ended up using the non-beta (those settings are stored in the DB). Other than that, the different DB's couldn't really interfere with device polling unless of course one DB knew not to poll while the other one continued to poll.

I should also note that when you see the "delayed for 30..." message, it means that the USB stick tried to talk to a device that didn't respond after 30 seconds; this is generally a bad sign because any requests sent to other devices would be blocked until that 30 seconds was up.

You could try to use these zentools to play with and optimize your routing. It's possible you've got a large object such as a fridge that's messing up your signal.


I tried to install Incontrol on my work computer yesterday, to see if it could be something wrong with my Windows installation. But unfortunatly did i experience the same delays on that one. So didn't seem to be related to the computer i'm using.

I should say that both my home and work computer have Win7 x64 installed, if that could result in this type of problem?


I run InControl on 64 bit as well as develop it there; it shouldn't interfere with the operations of it.

Show More Posts
Close