[OpenSIPS-Users] Delayed timer executions

[Dan Pascu]

I quite often find these warnings in syslog. They happen on an opensips 2.4 installation that is completely idle (it does not process any messages at all, no registrations, no calls, no presence, nothing). Sometimes I see these warnings right after I start opensips, during the startup log, while the others happen randomly during the day.

The excerpt below is what I got from opensips running for a few days and being completely idle the whole time:

./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-reinvite-pinger> has a 50000 us delay in execution
./opensips[21843]: WARNING:core:handle_timer_job: timer job <rls-notify> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <nt-pinger> has a 40000 us delay in execution
./opensips[21843]: WARNING:core:handle_timer_job: timer job <rls-pclean> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <rls-notify> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <nt-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 40000 us delay in execution
./opensips[21843]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <rls-notify> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <pua_clean> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 40000 us delay in execution
./opensips[21847]: WARNING:core:handle_timer_job: timer job <dlg-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 20000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <blcore-expire> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-reinvite-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-reinvite-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <rls-notify> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-reinvite-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <blcore-expire> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 30000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 40000 us delay in execution
./opensips[21846]: WARNING:core:handle_timer_job: timer job <ul-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-reinvite-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 30000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-reinvite-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-timer> has a 40000 us delay in execution
./opensips[21841]: WARNING:core:handle_timer_job: timer job <blcore-expire> has a 50000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 50000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <blcore-expire> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-reinvite-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <nt-pinger> has a 40000 us delay in execution
./opensips[21843]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 30000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <blcore-expire> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <rls-dbupdate> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <rls-notify> has a 40000 us delay in execution
./opensips[21843]: WARNING:core:handle_timer_job: timer job <nt-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <blcore-expire> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <nt-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 50000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <nt-pinger> has a 40000 us delay in execution
./opensips[18802]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 30000 us delay in execution
./opensips[18906]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 50000 us delay in execution
./opensips[19353]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 70000 us delay in execution
./opensips[21529]: WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 190 ms ago (now 290 ms), delaying execution
./opensips[21530]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 250000 us delay in execution
./opensips[21776]: WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 190 ms ago (now 290 ms), delaying execution
./opensips[21777]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 250000 us delay in execution
./opensips[21843]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 30000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <dlg-reinvite-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 30000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <blcore-expire> has a 40000 us delay in execution
./opensips[21843]: WARNING:core:handle_timer_job: timer job <nt-pinger> has a 40000 us delay in execution
./opensips[21850]: WARNING:core:handle_timer_job: timer job <tm-timer> has a 40000 us delay in execution
./opensips[16079]: WARNING:core:handle_timer_job: timer job <dlg-reinvite-pinger> has a 40000 us delay in execution
./opensips[12648]: WARNING:core:handle_timer_job: timer job <dlg-timer> has a 40000 us delay in execution
./opensips[12647]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 40000 us delay in execution
./opensips[22504]: WARNING:core:handle_timer_job: timer job <nt-pinger> has a 40000 us delay in execution
./opensips[22470]: WARNING:core:handle_timer_job: timer job <dlg-options-pinger> has a 40000 us delay in execution
./opensips[22508]: WARNING:core:handle_timer_job: timer job <ul-timer> has a 40000 us delay in execution

--
Dan