repeating message in logfile

What would be source for the below message appearing every 5 sec.? Is this a normal behaviour? I sometimes have delays in the response, wondering whether this could be the source

2016-11-20 09:46:16.546 openLuup.server:: request completed (10684 bytes, 1 chunks, 5091 ms) tcp{client}: 0x2933580
2016-11-20 09:46:16.683 openLuup.server:: GET /data_request?id=lu_status2&output_format=json&DataVersion=490366163&Timeout=60&MinimumDelay=1500&=1479557209209 HTTP/1.1 tcp{client}: 0x2933580
2016-11-20 09:46:21.743 openLuup.server:: request completed (10684 bytes, 1 chunks, 5060 ms) tcp{client}: 0x2933580
2016-11-20 09:46:21.905 openLuup.server:: GET /data_request?id=lu_status2&output_format=json&DataVersion=490366164&Timeout=60&MinimumDelay=1500&
=1479557209210 HTTP/1.1 tcp{client}: 0x2933580
2016-11-20 09:46:26.965 openLuup.server:: request completed (10684 bytes, 1 chunks, 5059 ms) tcp{client}: 0x2933580
2016-11-20 09:46:27.094 openLuup.server:: GET /data_request?id=lu_status2&output_format=json&DataVersion=490366165&Timeout=60&MinimumDelay=1500&=1479557209211 HTTP/1.1 tcp{client}: 0x2933580
2016-11-20 09:46:32.154 openLuup.server:: request completed (10684 bytes, 1 chunks, 5060 ms) tcp{client}: 0x2933580
2016-11-20 09:46:32.293 openLuup.server:: GET /data_request?id=lu_status2&output_format=json&DataVersion=490366166&Timeout=60&MinimumDelay=1500&
=1479557209212 HTTP/1.1 tcp{client}: 0x2933580

Thanks

This is absolutely standard.

Those requests are from AltUI and used to update the display state. You’ll see that the requests have both a minimum time (milliseconds) to wait before responding, and a maximum timeout (in seconds.). Normal behaviour is that there won’t be a response until something in the system has changed. In your case, this seems to be every five seconds or so.

These delays are managed asynchronously by the scheduler and don’t block the normal operation of plugins, etc.

Thanks for the info. I"ll have a look at the devices. I use some countdown timers but they should not be active all the time, every 5 sec seems too fast.

Is it possible this is the default polling of the verabridge or does this still means something changed on the vera?

That’s highly likely. I need to update VeraBridge to also run asynchronously, using the same method with its remote Vera. However, at the moment, it does indeed poll every 5 seconds. But it may be possible to stop it forcing an openLuup update if nothing has changed remotely. I’ll take a look.

Spot on, in fact. It seems that VeraBridge was updating its own LastUpdate variable on every 5-second poll. I’ve changed it to only update if a remote variable has changed, and put the fix in the testing branch.

If you update openLuup from the plugins page with testing in the Update box then you should pick up the latest. Would be very grateful of a report on how this goes.

Thanks (and karma)

Solved!
Thank you soo much

No, thank YOU again!