[BUG] When stopped OS on one of the cluster nodes, second one starts WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled

[mikle1397]

OpenSIPS version you are running

version: opensips 3.4.14 (x86_64/linux)
flags: STATS: On, SHM_EXTRA_STATS, DISABLE_NAGLE, USE_MCAST, SHM_MMAP, PKG_MALLOC, Q_MALLOC, F_MALLOC, FAST_LOCK-ADAPTIVE_WAIT
ADAPTIVE_WAIT_LOOPS=1024, MAX_RECV_BUFFER_SIZE 262144, MAX_LISTEN 16, MAX_URI_SIZE 1024, BUF_SIZE 65535
poll method support: poll, epoll, sigio_rt, select.
git revision: 277554f9b
main.c compiled on 23:30:55 Oct 10 2025 with gcc 12

Describe the bug
The bug occurs when Opensips is running in cluster mode (Active-backup setup). If one of the two nodes goes into the DOWN state, then problems with timers start on the second one, many WARNING messages appear in the logs like:

WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 100 ms ago (now 469638400 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 200 ms ago (now 469638500 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 300 ms ago (now 469638600 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 400 ms ago (now 469638700 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 500 ms ago (now 469638800 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 600 ms ago (now 469638900 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 700 ms ago (now 469639000 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 800 ms ago (now 469639100 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 900 ms ago (now 469639200 ms), delaying execution
WARNING:core:timer_ticker: timer task <tm-timer> already scheduled 1000 ms ago (now 469639200 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1000 ms ago (now 469639300 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1100 ms ago (now 469639400 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1200 ms ago (now 469639500 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1300 ms ago (now 469639600 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1400 ms ago (now 469639700 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1510 ms ago (now 469639810 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1610 ms ago (now 469639910 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1760 ms ago (now 469640060 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1800 ms ago (now 469640100 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1900 ms ago (now 469640200 ms), delaying execution
WARNING:core:timer_ticker: timer task <tm-timer> already scheduled 2000 ms ago (now 469640200 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 2010 ms ago (now 469640310 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 2110 ms ago (now 469640410 ms), delaying execution
NOTICE:tm:timer_routine: time spent: 2.197s now at 75%+ capacity, inuse_transactions: 37
NOTICE:tm:utimer_routine: time spent: 2.096s now at 75%+ capacity, inuse_transactions: 37
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 100 ms ago (now 469647570 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 200 ms ago (now 469647670 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 300 ms ago (now 469647770 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 400 ms ago (now 469647870 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 500 ms ago (now 469647970 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 600 ms ago (now 469648070 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 700 ms ago (now 469648170 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 800 ms ago (now 469648270 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 900 ms ago (now 469648370 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1000 ms ago (now 469648470 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1100 ms ago (now 469648570 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1200 ms ago (now 469648670 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1300 ms ago (now 469648770 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1400 ms ago (now 469648870 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1500 ms ago (now 469648970 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1600 ms ago (now 469649070 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1700 ms ago (now 469649170 ms), delaying execution
WARNING:core:timer_ticker: timer task <tm-timer> already scheduled 1000 ms ago (now 469649170 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1800 ms ago (now 469649270 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 1900 ms ago (now 469649370 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 100 ms ago (now 469649370 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 2000 ms ago (now 469649470 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 200 ms ago (now 469649470 ms), delaying execution
NOTICE:tm:utimer_routine: time spent: 2.100s now at 75%+ capacity, inuse_transactions: 15
NOTICE:tm:timer_routine: time spent: 1.391s now at 75%+ capacity, inuse_transactions: 15
NOTICE:tm:utimer_routine: time spent: 0.289s now at 75%+ capacity, inuse_transactions: 13
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 100 ms ago (now 469754590 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 200 ms ago (now 469754690 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 300 ms ago (now 469754790 ms), delaying execution
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled 400 ms ago (now 469754890 ms), delaying execution

OS/environment information

• Operating System: Debian 12
• OpenSIPS installation: git

[razvancrainea]

Thank you for your report!
Is opensips still stuck? Otherwise, when it gets stuck again, please run opensips-cli -x trap and send us the resulted file.

Best regards,
Răzvan

[mikle1397]

The second node is already enabled. The next time I have to turn it off, I'll run opensips-cli -x trap.

[github-actions]

Any updates here? No progress has been made in the last 15 days, marking as stale. Will close this issue if no further updates are made in the next 30 days.