[OpenSIPS-Devel] potential timer shifting

[Bogdan-Andrei Iancu]

Hi Edwin,

The logs are only opensips related - I mean there is nothing wrong with 
the system timer; the problem is in the opensips timer

What the log wants to say is that there is timer that hits every 1 sec 
and runs a callback that takes more than 1s - it actually takes 7s in 
average :(......This long execution time of the callback gets the 
opensips timer desynchronized (if you have 7 secs delay every second :) ).

The guilty callback is the ul-timer (usrloc modules) which dumps 
registrations into DB - probably you have a very slow DB update or so. 
You can enable mysql thresholds (see 
http://www.opensips.org/html/docs/modules/1.9.x/db_mysql.html#id249058) 
- to pin point that slow db query.

Regards,

Bogdan-Andrei Iancu
OpenSIPS Founder and Developer
http://www.opensips-solutions.com


On 03/26/2013 02:13 PM, Edwin wrote:
> I upgraded opensips from 1.7.2 to the newest version 1.9.0-1-99301. I
> installed opensips, opensips-dbg and opensips-mysql-module from
> apt.opensips.org (deb http://apt.opensips.org/ stable19 main).
> It's running on a virtual server (Proxmox KVM SMP Debian 3.2.39-2 x86_64
> GNU/Linux):
>
> cat /sys/devices/system/clocksource/clocksource0/current_clocksource
> kvm-clock
>
> Since the upgrade I see these errors in my log files:
> Mar 26 09:21:39 opensips /usr/sbin/opensips[7057]:
> CRITICAL:core:timer_ticker: timer handler<ul-timer>  lasted (7490000 us) for
> more than timer tick (1000000 us) ->  potential timer shifting
> Mar 26 09:22:40 opensips /usr/sbin/opensips[7057]:
> CRITICAL:core:timer_ticker: timer handler<ul-timer>  lasted (7030000 us) for
> more than timer tick (1000000 us) ->  potential timer shifting
> Mar 26 09:23:42 opensips /usr/sbin/opensips[7057]:
> CRITICAL:core:timer_ticker: timer handler<ul-timer>  lasted (7190000 us) for
> more than timer tick (1000000 us) ->  potential timer shifting
> Mar 26 09:24:45 opensips /usr/sbin/opensips[7057]:
> CRITICAL:core:timer_ticker: timer handler<ul-timer>  lasted (8550000 us) for
> more than timer tick (1000000 us) ->  potential timer shifting
> Mar 26 09:25:44 opensips /usr/sbin/opensips[7057]:
> CRITICAL:core:timer_ticker: timer handler<ul-timer>  lasted (6050000 us) for
> more than timer tick (1000000 us) ->  potential timer shifting
> etc etc...
>
> Any ideas what can cause these errors (and how to solve the problem?)
>
>
>
> --
> View this message in context: http://opensips-open-sip-server.1449251.n2.nabble.com/potential-timer-shifting-tp7585511.html
> Sent from the OpenSIPS - Devel mailing list archive at Nabble.com.
>
> _______________________________________________
> Devel mailing list
> Devel at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/devel
>