Hi Bogdan, Solved! OpenSIPS (latest SVN) no longer crashes after MySQL restarts.
Thanks, Om. On Sun, Mar 1, 2009 at 11:33 AM, Om Bikram Thapa <[email protected]> wrote: > Hi Bogdan, > > I will update and let you know the results. > > Regards, > Om. > > On Sat, Feb 28, 2009 at 3:08 AM, Bogdan-Andrei Iancu > <[email protected]> wrote: >> Hi Om, >> >> finally, after some struggle with the libmysqlclient, I hope the issue is >> solve. Please give it another try and let me know the result. >> >> Thanks and regards, >> Bogdan >> >> Om Bikram Thapa wrote: >>> >>> Hi Bogdan, >>> >>> Just updated to latest svn, but I still see crashes after MySQL >>> restart. The syslog shows following 3 lines after MySQL restart and >>> registration attempt: >>> >>> -- >>> Feb 19 12:17:43 proxy /usr/local/sbin/opensips[2333]: >>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() >>> failed: (1) Unknown prepared statement handler (1) given to >>> mysql_stmt_execute >>> Feb 19 12:17:43 proxy /usr/local/sbin/opensips[2333]: >>> ERROR:auth_db:get_ha1: failed to query database >>> Feb 19 12:18:13 proxy kernel: [ 105.116919] opensips[2333]: segfault >>> at 4c8 ip 7f167c109283 sp 7fff8525b270 error 4 in >>> libmysqlclient.so.15.0.0[7f167c0ac000+1bf000] >>> -- >>> >>> At first attempt, the registration fails with "SIP/2.0 500 Server >>> Internal Error" returned by the server, and subsequent registration >>> attempt crashes the server. >>> >>> gdb backtrace gives following details: >>> >>> --- >>> #0 0x00007f0119426283 in mysql_stmt_result_metadata () from >>> /usr/lib/libmysqlclient.so.15 >>> (gdb) bt >>> #0 0x00007f0119426283 in mysql_stmt_result_metadata () from >>> /usr/lib/libmysqlclient.so.15 >>> #1 0x00007f01197d8f82 in db_mysql_do_prepared_query (conn=0x78b278, >>> query=<value optimized out>, v=0x7fff22576880, n=<value optimized >>> out>, uv=0x0, un=0) >>> at dbase.c:368 >>> #2 0x00007f01197daccd in db_mysql_query (_h=0x78b278, _k=<value >>> optimized out>, _op=<value optimized out>, _v=0x7fff22576880, >>> _c=<value optimized out>, >>> _n=1, _nc=2, _o=0x0, _r=0x7fff22576920) at dbase.c:603 >>> #3 0x00007f0115f56398 in authorize (_m=0x78fe98, _realm=<value >>> optimized out>, _table=<value optimized out>, _hftype=<value optimized >>> out>) >>> at authorize.c:107 >>> #4 0x000000000040f869 in do_action (a=0x780a48, msg=0x78fe98) at >>> action.c:961 >>> #5 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>> msg=0x78fe98) at action.c:139 >>> #6 0x000000000046f194 in eval_elem (e=0x780b18, msg=0x78fe98, >>> val=0x0) at route.c:1189 >>> #7 0x00000000004708ed in eval_expr (e=0x27648e0, msg=0x78fe98, >>> val=0x0) at route.c:1486 >>> #8 0x000000000047089c in eval_expr (e=0x780b60, msg=0x78fe98, >>> val=0x0) at route.c:1502 >>> #9 0x00000000004708c5 in eval_expr (e=0x780ba8, msg=0x78fe98, >>> val=0x0) at route.c:1507 >>> #10 0x000000000040f91c in do_action (a=0x780f40, msg=0x78fe98) at >>> action.c:688 >>> #11 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>> msg=0x78fe98) at action.c:139 >>> #12 0x00000000004114d3 in do_action (a=0x781eb0, msg=0x78fe98) at >>> action.c:705 >>> #13 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>> msg=0x78fe98) at action.c:139 >>> #14 0x0000000000410e07 in do_action (a=0x77ee88, msg=0x78fe98) at >>> action.c:119 >>> #15 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>> msg=0x78fe98) at action.c:139 >>> #16 0x00000000004114d3 in do_action (a=0x77f028, msg=0x78fe98) at >>> action.c:705 >>> #17 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>> msg=0x78fe98) at action.c:139 >>> #18 0x00000000004125fe in run_top_route (a=0x777e78, msg=0x78fe98) at >>> action.c:119 >>> #19 0x000000000045e898 in receive_msg ( >>> buf=0x749180 "REGISTER sip:x.x.x.x SIP/2.0\r\nCSeq: 58 >>> REGISTER\r\nVia: SIP/2.0/UDP >>> >>> x.x.x.x:5068;branch=z9hG4bK5ca4685b-befc-dd11-911f-0015c5404858;rport\r\nUser-Agent: >>> Ekiga/2.0.12\r\nAuthorization: Dig"..., len=750, >>> rcv_info=0x7fff22578090) at receive.c:165 >>> #20 0x000000000049d3a6 in udp_rcv_loop () at udp_server.c:449 >>> #21 0x00000000004291fb in main (argc=<value optimized out>, >>> argv=0x7fff22578278) at main.c:778 >>> --- >>> >>> I have tried several times and each time I get the same sequence of >>> results. >>> >>> Thanks, >>> Om. >>> >>> On Thu, Feb 19, 2009 at 11:22 AM, Om Bikram Thapa <[email protected]> >>> wrote: >>> >>>> >>>> Thanks Bogdan. I will update and let you know. >>>> >>>> Regards, >>>> Om. >>>> >>>> On Thu, Feb 19, 2009 at 2:48 AM, Bogdan-Andrei Iancu >>>> <[email protected]> wrote: >>>> >>>>> >>>>> Hi Om, >>>>> >>>>> There is a fix available on SVN - please update and see if it works now. >>>>> >>>>> Thanks and regards, >>>>> Bogdan >>>>> >>>>> Om Bikram Thapa wrote: >>>>> >>>>>> >>>>>> Hi Bogdan, >>>>>> >>>>>> I have submitted the bug on the tracker. >>>>>> >>>>>> Thanks, >>>>>> Om. >>>>>> >>>>>> On Tue, Feb 17, 2009 at 3:50 AM, Bogdan-Andrei Iancu >>>>>> <[email protected]> wrote: >>>>>> >>>>>> >>>>>>> >>>>>>> Hi Om, >>>>>>> >>>>>>> Thanks for the report - I may have an idea what is the problem; I will >>>>>>> try >>>>>>> to reproduce the crash in the following days. >>>>>>> >>>>>>> Could you please open a bug report on the tracker - just not to forget >>>>>>> about >>>>>>> it :D (see http://www.opensips.org/index.php?n=Development.Tracker) >>>>>>> >>>>>>> Regards, >>>>>>> Bogdan >>>>>>> >>>>>>> Om Bikram Thapa wrote: >>>>>>> >>>>>>> >>>>>>>> >>>>>>>> Hi, >>>>>>>> >>>>>>>> OpenSIPS is dying with "libmysqlclient.so Segmentation Fault" after >>>>>>>> MySQL server restart. The log shows >>>>>>>> "opensips[16769]: segfault at 4c8 ip 7f36728bf283 sp 7fff7ba11a90 >>>>>>>> error 4 in libmysqlclient.so.15.0.0[7f3672862000+1bf000]" >>>>>>>> >>>>>>>> and gdb bt shows: >>>>>>>> >>>>>>>> ------------- >>>>>>>> #0 0x00007f36728bf283 in mysql_stmt_result_metadata () from >>>>>>>> /usr/lib/libmysqlclient.so.15 >>>>>>>> #1 0x00007f3672c707b7 in db_mysql_do_prepared_query (conn=0x78b278, >>>>>>>> query=<value optimized out>, v=0x7fff7ba11d20, n=1, uv=0x0, un=0) at >>>>>>>> dbase.c:363 >>>>>>>> #2 0x00007f3672c72e1f in db_mysql_query (_h=0x78b278, _k=<value >>>>>>>> optimized out>, _op=<value optimized out>, _v=0x7fff7ba11d20, >>>>>>>> _c=<value optimized out>, >>>>>>>> _n=1, _nc=2, _o=0x0, _r=0x7fff7ba11dc0) at dbase.c:591 >>>>>>>> #3 0x00007f366f3f0398 in authorize (_m=0x78fe98, _realm=<value >>>>>>>> optimized out>, _table=<value optimized out>, _hftype=<value >>>>>>>> optimized >>>>>>>> out>) >>>>>>>> at authorize.c:107 >>>>>>>> #4 0x000000000040f869 in do_action (a=0x780a48, msg=0x78fe98) at >>>>>>>> action.c:961 >>>>>>>> #5 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>>>>>>> msg=0x78fe98) at action.c:139 >>>>>>>> #6 0x000000000046f194 in eval_elem (e=0x780b18, msg=0x78fe98, >>>>>>>> val=0x0) at route.c:1189 >>>>>>>> #7 0x00000000004708ed in eval_expr (e=0x120b1e0, msg=0x78fe98, >>>>>>>> val=0x0) at route.c:1486 >>>>>>>> #8 0x000000000047089c in eval_expr (e=0x780b60, msg=0x78fe98, >>>>>>>> val=0x0) at route.c:1502 >>>>>>>> #9 0x00000000004708c5 in eval_expr (e=0x780ba8, msg=0x78fe98, >>>>>>>> val=0x0) at route.c:1507 >>>>>>>> #10 0x000000000040f91c in do_action (a=0x780f40, msg=0x78fe98) at >>>>>>>> action.c:688 >>>>>>>> #11 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>>>>>>> msg=0x78fe98) at action.c:139 >>>>>>>> #12 0x00000000004114d3 in do_action (a=0x781eb0, msg=0x78fe98) at >>>>>>>> action.c:705 >>>>>>>> #13 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>>>>>>> msg=0x78fe98) at action.c:139 >>>>>>>> #14 0x0000000000410e07 in do_action (a=0x77ee88, msg=0x78fe98) at >>>>>>>> action.c:119 >>>>>>>> #15 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>>>>>>> msg=0x78fe98) at action.c:139 >>>>>>>> #16 0x00000000004114d3 in do_action (a=0x77f028, msg=0x78fe98) at >>>>>>>> action.c:705 >>>>>>>> #17 0x000000000040e7e5 in run_action_list (a=<value optimized out>, >>>>>>>> msg=0x78fe98) at action.c:139 >>>>>>>> #18 0x00000000004125fe in run_top_route (a=0x777e78, msg=0x78fe98) at >>>>>>>> action.c:119 >>>>>>>> #19 0x000000000045e898 in receive_msg ( >>>>>>>> buf=0x749180 "REGISTER sip:xxxxx SIP/2.0\r\nCSeq: 2 >>>>>>>> REGISTER\r\nVia: SIP/2.0/UDP >>>>>>>> >>>>>>>> >>>>>>>> >>>>>>>> x.x.x.x:5061;branch=z9hG4bK60f1b2dd-57fa-dd11-9401-0015c5404858;rport\r\nUser-Agent: >>>>>>>> Ekiga/2.0.12\r\nAuthorization: Dige"..., len=749, >>>>>>>> rcv_info=0x7fff7ba13530) at receive.c:165 >>>>>>>> #20 0x000000000049d3a6 in udp_rcv_loop () at udp_server.c:449 >>>>>>>> #21 0x00000000004291fb in main (argc=<value optimized out>, >>>>>>>> argv=0x7fff7ba13718) at main.c:778 >>>>>>>> ------------ >>>>>>>> >>>>>>>> Server runs fine after restart until MySQL is restarted again. In my >>>>>>>> lab setup, OpenSIPS is getting killed triggered by registration >>>>>>>> request every morning (probably due to MySQL being restarted daily >>>>>>>> with logrotate). >>>>>>>> >>>>>>>> The server is the latest trunk on Debian lenny/AMD64. >>>>>>>> >>>>>>>> Thanks, >>>>>>>> Om. >>>>>>>> >>>>>>>> _______________________________________________ >>>>>>>> Users mailing list >>>>>>>> [email protected] >>>>>>>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users >>>>>>>> >>>>>>>> >>>>>>>> >>>>>>>> >>>>>>> >>>>>>> >>>>>> >>>>>> >>>>> >>>>> >>> >>> >> >> > _______________________________________________ Users mailing list [email protected] http://lists.opensips.org/cgi-bin/mailman/listinfo/users
