Hello!
Some time ago i wrote about a problem when Opensips began reject all calls with
404 code.
Unfortunately i did not received any answer.
Since that time i have made upgrade from 2.1.2 to 2.2.1 and today i have got
the same problem. Opensips began rejects all calls with 404 code.
Based on the information from log, i make suggest that problem began when
dr_reload command has been received.
And the problem has been solved, again, when the dr_reload command has been
received (the period about two commands is about a couple of minutes).
I please help to solve the problem.
Thank you.
mailto:[email protected]
Hello!
Recently i begun to get such problem.
Opensips rejects all calls with 404 code. I can solve the problem by making
dr_reload.
I tried to analyze a log and what i could found you can see in attachment.
As i could understand last problem began after dr_reload.
Thank you for any help.
mailto:[email protected]
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30942]:
INFO:drouting:dr_reload_cmd: dr_reload MI command received!
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30954]:
CRITICAL:db_mysql:wrapper_single_mysql_stmt_execute: driver
error (1062): Duplicate entry '397f3bf2b36c1ebfab9ec106b08a65cb-2016-08-30
15:30:19' for key 'hash_idx'
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30954]:
ERROR:acc:acc_db_request: failed to insert into database
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30952]:
rc_get_seqnbr: couldn't open sequence file /var/run/radius.
seq: Permission denied
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30950]:
CRITICAL:db_mysql:wrapper_single_mysql_stmt_execute: driver
error (1062): Duplicate entry '77d307953a7c0b9ae3bba735d803e36c-2016-08-30
15:30:19' for key 'hash_idx'
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30950]:
ERROR:acc:acc_db_request: failed to insert into database
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30942]:
INFO:drouting:add_dst: pgw tree 0x7fd137b82588
Aug 30 15:30:19 /usr/local/opensips2.2/sbin/opensips[30942]: last message
repeated 198 times
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30966]:
CRITICAL:db_mysql:wrapper_single_mysql_stmt_execute: driver
error (1062): Duplicate entry '964d31651217d9ce9c06fafb082518ee-2016-08-30
15:30:19' for key 'hash_idx'
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30966]:
ERROR:acc:acc_db_request: failed to insert into database
Aug 30 15:30:19 opensips-main /usr/local/opensips2.2/sbin/opensips[30964]:
INFO:registrar:update_contacts: too many contacts for AOR <
0008439>, max=1
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30966]:
INFO:registrar:update_contacts: too many contacts for AOR <
0008439>, max=1
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30965]:
INFO:registrar:update_contacts: too many contacts for AOR <
0000276>, max=1
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30957]:
INFO:registrar:update_contacts: too many contacts for AOR <
0008439>, max=1
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30964]:
INFO:registrar:update_contacts: too many contacts for AOR <
0008439>, max=1
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already
scheduled for 705886160 ms (now 705886260 ms), it may overlap..
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already
scheduled for 705886260 ms (now 705886360 ms), it may overlap..
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already
scheduled for 705886360 ms (now 705886450 ms), it may overlap..
Aug 30 15:30:20 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already
scheduled for 705886450 ms (now 705886550 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already
scheduled for 705886550 ms (now 705886650 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
:
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already
scheduled for 705886360 ms (now 705886450 ms), it may overlap..
Aug 30 15:30:20 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:20 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already
scheduled for 705886450 ms (now 705886550 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already
scheduled for 705886550 ms (now 705886650 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled for
705886650 ms (now 705886750 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled for
705886750 ms (now 705886850 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled for
705886850 ms (now 705886950 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled for
705886950 ms (now 705887050 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:timer_ticker: timer task <tm-timer> already scheduled for
705886060 ms (now 705887050 ms), it may overlap..
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled for
705887050 ms (now 705887150 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled for
705887150 ms (now 705887250 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled for
705887250 ms (now 705887350 ms), it may overlap..
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30950]:
INFO:db_mysql:switch_state_to_disconnected: disconnect event for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30950]:
INFO:db_mysql:reset_all_statements: reseting all statements on connection:
(0x7fd15649c288) 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30950]:
INFO:db_mysql:connect_with_retry: re-connected successful for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30950]:
INFO:db_mysql:db_mysql_do_prepared_query: reconnected to mysql server ->
re-init the statement
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30948]:
INFO:db_mysql:switch_state_to_disconnected: disconnect event for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30948]:
INFO:db_mysql:reset_all_statements: reseting all statements on connection:
(0x7fd15649cac8) 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30948]:
INFO:db_mysql:connect_with_retry: re-connected successful for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30948]:
INFO:db_mysql:db_mysql_do_prepared_query: reconnected to mysql server ->
re-init the statement
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30954]:
INFO:db_mysql:switch_state_to_disconnected: disconnect event for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30954]:
INFO:db_mysql:reset_all_statements: reseting all statements on connection:
(0x7fd15649c288) 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30954]:
INFO:db_mysql:connect_with_retry: re-connected successful for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30954]:
INFO:db_mysql:db_mysql_do_prepared_query: reconnected to mysql server ->
re-init the statement
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30949]:
INFO:db_mysql:switch_state_to_disconnected: disconnect event for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30949]:
INFO:db_mysql:reset_all_statements: reseting all statements on connection:
(0x7fd15649c288) 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30949]:
INFO:db_mysql:connect_with_retry: re-connected successful for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30949]:
INFO:db_mysql:db_mysql_do_prepared_query: reconnected to mysql server ->
re-init the statement
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30944]:
WARNING:core:utimer_ticker: utimer task <tm-utimer> already scheduled for
705887350 ms (now 705887450 ms), it may overlap..
Aug 30 15:30:21 /usr/local/opensips2.2/sbin/opensips[30944]: last message
repeated 3 times
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30952]:
INFO:db_mysql:switch_state_to_disconnected: disconnect event for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30952]:
INFO:db_mysql:reset_all_statements: reseting all statements on connection:
(0x7fd15649cac8) 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30952]:
INFO:db_mysql:connect_with_retry: re-connected successful for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30952]:
INFO:db_mysql:db_mysql_do_prepared_query: reconnected to mysql server ->
re-init the statement
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30945]:
INFO:db_mysql:switch_state_to_disconnected: disconnect event for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30945]:
INFO:db_mysql:reset_all_statements: reseting all statements on connection:
(0x7fd15649ce00) 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30945]:
INFO:db_mysql:connect_with_retry: re-connected successful for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30945]:
INFO:db_mysql:db_mysql_do_prepared_query: reconnected to mysql server ->
re-init the statement
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30946]:
INFO:db_mysql:switch_state_to_disconnected: disconnect event for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30946]:
INFO:db_mysql:reset_all_statements: reseting all statements on connection:
(0x7fd15649cac8) 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30946]:
INFO:db_mysql:connect_with_retry: re-connected successful for 0x7fd15649b910
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30946]:
INFO:db_mysql:db_mysql_do_prepared_query: reconnected to mysql server ->
re-init the statement
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30949]:
CRITICAL:db_mysql:wrapper_single_mysql_stmt_execute: driver error (1062):
Duplicate entry '2237763521985' for key 'PRIMARY'
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30954]:
CRITICAL:db_mysql:wrapper_single_mysql_stmt_execute: driver error (1062):
Duplicate entry '8672595991717' for key 'PRIMARY'
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30954]:
ERROR:dialog:update_dialog_dbinfo: could not add another dialog to db
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30949]:
ERROR:dialog:update_dialog_dbinfo: could not add another dialog to db
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30949]:
WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 60000 us delay in
execution
Aug 30 15:30:21 opensips-main /usr/local/opensips2.2/sbin/opensips[30949]:
WARNING:core:handle_timer_job: utimer job <tm-utimer> has a 60000 us delay in
execution
_______________________________________________
Users mailing list
[email protected]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
