db_mysql error, crash

classic Classic list List threaded Threaded
15 messages Options
Reply | Threaded
Open this post in threaded view
|

db_mysql error, crash

Jeff Pyle
db_mysql error, crash Hello,

I woke up this morning to this on Opensips 1.5.0:

Mar 25 08:50:10 opensips[12439]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:50:10 opensips[12439]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:50:18 opensips[12412]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:50:18 opensips[12412]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:50:19 opensips[12439]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:50:19 opensips[12439]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:50:23 opensips[12412]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:50:23 opensips[12412]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:50:27 opensips[12412]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:50:27 opensips[12412]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:50:27 opensips[12439]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:50:27 opensips[12439]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:50:30 opensips[12439]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:50:30 opensips[12439]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:50:54 opensips[12412]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:50:54 opensips[12412]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:51:48 opensips[12439]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:51:48 opensips[12439]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:51:55 opensips[12412]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:51:55 opensips[12412]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:52:16 opensips[12412]: ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute() failed(2): (1210) Incorrect arguments to mysql_stmt_execute
Mar 25 08:52:16 opensips[12412]: ERROR:dialog:remove_dialog_from_db: failed to delete database information
Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: child process 12435 exited normally, status=1
Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: terminating due to SIGCHLD
Mar 25 08:52:25 opensips[12412]: INFO:core:sig_usr: signal 15 received
Mar 25 08:52:25 opensips[12468]: INFO:core:sig_usr: signal 15 received
Mar 25 08:52:25 opensips[12437]: INFO:core:sig_usr: signal 15 received

I have all the memory debug output from the crash as well (several hundred lines).  Unfortunately no core was generated even with ‘ulimit –c 0’ and a working directory set.


- Jeff


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Bogdan-Andrei Iancu
Hi Jeff,

What OS are you using? and what version for mysql client lib?

Thanks and regards,
Bogdan

Jeff Pyle wrote:

> Hello,
>
> I woke up this morning to this on Opensips 1.5.0:
>
> Mar 25 08:50:10 opensips[12439]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:50:10 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:50:18 opensips[12412]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:50:18 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:50:19 opensips[12439]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:50:19 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:50:23 opensips[12412]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:50:23 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:50:27 opensips[12412]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:50:27 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:50:27 opensips[12439]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:50:27 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:50:30 opensips[12439]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:50:30 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:50:54 opensips[12412]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:50:54 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:51:48 opensips[12439]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:51:48 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:51:55 opensips[12412]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:51:55 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:52:16 opensips[12412]:
> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
> Mar 25 08:52:16 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
> failed to delete database information
> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: child process
> 12435 exited normally, status=1
> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: terminating
> due to SIGCHLD
> Mar 25 08:52:25 opensips[12412]: INFO:core:sig_usr: signal 15 received
> Mar 25 08:52:25 opensips[12468]: INFO:core:sig_usr: signal 15 received
> Mar 25 08:52:25 opensips[12437]: INFO:core:sig_usr: signal 15 received
>
> I have all the memory debug output from the crash as well (several
> hundred lines). Unfortunately no core was generated even with ‘ulimit
> –c 0’ and a working directory set.
>
>
> - Jeff
>
> ------------------------------------------------------------------------
>
> _______________________________________________
> Users mailing list
> [hidden email]
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>  


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Jeff Pyle
Hi Bogdan,

CentOS 5.2 running 2.6.18-92.1.22.el5xen.

Mysql client lib came from Mysql's own RPM named
MySQL-Cluster-gpl-client-6.3.20-0.rhel5.  The file itself is called
libmysqlclient.so.16.0.0 or libmysqlclient_r.so.16.0.0.

Does that answer the version question?  I'm not sure how to tell otherwise.


- Jeff



On 3/25/09 2:22 PM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:

> Hi Jeff,
>
> What OS are you using? and what version for mysql client lib?
>
> Thanks and regards,
> Bogdan
>
> Jeff Pyle wrote:
>> Hello,
>>
>> I woke up this morning to this on Opensips 1.5.0:
>>
>> Mar 25 08:50:10 opensips[12439]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:50:10 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:50:18 opensips[12412]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:50:18 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:50:19 opensips[12439]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:50:19 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:50:23 opensips[12412]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:50:23 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:50:27 opensips[12412]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:50:27 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:50:27 opensips[12439]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:50:27 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:50:30 opensips[12439]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:50:30 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:50:54 opensips[12412]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:50:54 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:51:48 opensips[12439]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:51:48 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:51:55 opensips[12412]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:51:55 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:52:16 opensips[12412]:
>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>> Mar 25 08:52:16 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>> failed to delete database information
>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: child process
>> 12435 exited normally, status=1
>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: terminating
>> due to SIGCHLD
>> Mar 25 08:52:25 opensips[12412]: INFO:core:sig_usr: signal 15 received
>> Mar 25 08:52:25 opensips[12468]: INFO:core:sig_usr: signal 15 received
>> Mar 25 08:52:25 opensips[12437]: INFO:core:sig_usr: signal 15 received
>>
>> I have all the memory debug output from the crash as well (several
>> hundred lines). Unfortunately no core was generated even with Œulimit
>> ­c 0¹ and a working directory set.
>>
>>
>> - Jeff
>>
>> ------------------------------------------------------------------------
>>
>> _______________________________________________
>> Users mailing list
>> [hidden email]
>> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>>  
>


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Jeff Pyle
Hi Bogdan,

Is this information helpful for you?  Am I looking at the correct item?

I've had several more crashes since this one, but absolutely no hint as to
why.  No mysql errors on these.  Nor core dumps.  It seems to happen only
after the proxy is up for a while.  I'm attempting to run it with debug=4
but it's hard to keep track of that much data.  Hopefully I'll catch a crash
at debug=4 to know at least which module is causing it.


Thanks,
Jeff



On 3/25/09 2:28 PM, "Jeff Pyle" <[hidden email]> wrote:

> Hi Bogdan,
>
> CentOS 5.2 running 2.6.18-92.1.22.el5xen.
>
> Mysql client lib came from Mysql's own RPM named
> MySQL-Cluster-gpl-client-6.3.20-0.rhel5.  The file itself is called
> libmysqlclient.so.16.0.0 or libmysqlclient_r.so.16.0.0.
>
> Does that answer the version question?  I'm not sure how to tell otherwise.
>
>
> - Jeff
>
>
>
> On 3/25/09 2:22 PM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>
>> Hi Jeff,
>>
>> What OS are you using? and what version for mysql client lib?
>>
>> Thanks and regards,
>> Bogdan
>>
>> Jeff Pyle wrote:
>>> Hello,
>>>
>>> I woke up this morning to this on Opensips 1.5.0:
>>>
>>> Mar 25 08:50:10 opensips[12439]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:50:10 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:50:18 opensips[12412]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:50:18 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:50:19 opensips[12439]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:50:19 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:50:23 opensips[12412]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:50:23 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:50:27 opensips[12412]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:50:27 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:50:27 opensips[12439]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:50:27 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:50:30 opensips[12439]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:50:30 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:50:54 opensips[12412]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:50:54 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:51:48 opensips[12439]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:51:48 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:51:55 opensips[12412]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:51:55 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:52:16 opensips[12412]:
>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>> Mar 25 08:52:16 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>> failed to delete database information
>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: child process
>>> 12435 exited normally, status=1
>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: terminating
>>> due to SIGCHLD
>>> Mar 25 08:52:25 opensips[12412]: INFO:core:sig_usr: signal 15 received
>>> Mar 25 08:52:25 opensips[12468]: INFO:core:sig_usr: signal 15 received
>>> Mar 25 08:52:25 opensips[12437]: INFO:core:sig_usr: signal 15 received
>>>
>>> I have all the memory debug output from the crash as well (several
>>> hundred lines). Unfortunately no core was generated even with Œulimit
>>> ­c 0¹ and a working directory set.
>>>
>>>
>>> - Jeff


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Jacek Konieczny
In reply to this post by Jeff Pyle
Hello,

On Wed, Mar 25, 2009 at 09:36:42AM -0400, Jeff Pyle wrote:
>  I woke up this morning to this on Opensips 1.5.0:

The log:
Mar 27 08:22:52 gtw1-ms opensips[14929]: ERROR:core:re_init_statement: failed while mysql_stmt_prepare: Unknown prepared statement handler (3) given to mysql_stmt_close
Mar 27 08:22:52 gtw1-ms opensips[14929]: ERROR:core:db_mysql_do_prepared_query: failed to re-init statement!
Mar 27 08:22:52 gtw1-ms opensips[14929]: ERROR:core:acc_db_request: failed to insert into database

Then two requests handled and:
Mar 27 08:23:14 gtw1-ms opensips[14949]: CRITICAL:core:receive_fd: EOF on 10
Mar 27 08:23:14 gtw1-ms opensips[14914]: INFO:core:handle_sigs: child process 14931 exited by a signal 11
Mar 27 08:23:14 gtw1-ms opensips[14914]: INFO:core:handle_sigs: core was generated
Mar 27 08:23:14 gtw1-ms opensips[14914]: INFO:core:handle_sigs: terminating due to SIGCHLD
Mar 27 08:23:14 gtw1-ms opensips[14925]: INFO:core:sig_usr: signal 15 received
Mar 27 08:23:14 gtw1-ms opensips[14937]: INFO:core:sig_usr: signal 15 received

And what GDB shows on the core file:

Core was generated by `opensips'.
Program terminated with signal 11, Segmentation fault.
#0  0xb7b673bd in mysql_stmt_result_metadata (stmt=0x828cb50) at libmysql.c:2254
2254      result->methods=      stmt->mysql->methods;

(gdb) bt
#0  0xb7b673bd in mysql_stmt_result_metadata (stmt=0x828cb50) at libmysql.c:2254
#1  0xb7cb6f43 in db_mysql_do_prepared_query (conn=0x8191f38, query=0xb7ccdf08, v=0xbfde5608, n=1, uv=0x0,
    un=0) at dbase.c:432
#2  0xb7cb84a7 in db_mysql_query (_h=0x8191f38, _k=0xbfde5638, _op=0x0, _v=0xbfde5608, _c=0x8195ed0, _n=1,
    _nc=2, _o=0x0, _r=0xbfde5654) at dbase.c:676
#3  0xb79d6e72 in authorize (_m=0x8194948, _realm=<value optimized out>, _table=<value optimized out>,
    _hftype=HDR_AUTHORIZATION_T) at authorize.c:107
#4  0x08054ba3 in do_action (a=0x8183a60, msg=0x8194948) at action.c:962
#5  0x08053903 in run_action_list (a=0x8183a60, msg=0x8194948) at action.c:139
#6  0x08096a57 in eval_expr (e=0x8183ac8, msg=0x8194948, val=0x0) at route.c:1189
#7  0x080965c5 in eval_expr (e=0x8183af0, msg=0x8194948, val=0x0) at route.c:1502
#8  0x08096602 in eval_expr (e=0x8183b18, msg=0x8194948, val=0x0) at route.c:1507
#9  0x08054bd2 in do_action (a=0x8184460, msg=0x8194948) at action.c:689
#10 0x08053903 in run_action_list (a=0x8184460, msg=0x8194948) at action.c:139
#11 0x080571c9 in do_action (a=0x81844c8, msg=0x8194948) at action.c:712
#12 0x08053903 in run_action_list (a=0x8182c80, msg=0x8194948) at action.c:139
#13 0x08057f27 in run_top_route (a=0x8182c80, msg=0x8194948) at action.c:119
#14 0x0808b951 in receive_msg (
    buf=0x8157be0 "REGISTER sip:sip-gtw1.axeos.nl SIP/2.0\r\nVia: SIP/2.0/UDP 83.98.196.74:5060;branch=z9hG4bK4aa5f9e2;rport\r\nFrom: <sip:[hidden email]>;tag=as14f8aa61\r\nTo: <sip:[hidden email]>\r\n"..., len=671, rcv_info=0xbfde6544) at receive.c:165
#15 0x080bf625 in udp_rcv_loop () at udp_server.c:449
#16 0x08069e99 in main (argc=1, argv=0xbfde6704) at main.c:779

(gdb) print stmt->mysql
$2 = (MYSQL *) 0x0

(gdb) up
#1  0xb7cb6f43 in db_mysql_do_prepared_query (conn=0x8191f38, query=0xb7ccdf08, v=0xbfde5608, n=1, uv=0x0, un=0) at dbase.c:432
432                     CON_RESULT(conn) = mysql_stmt_result_metadata(ctx->stmt);


Greets,
        Jacek

_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Iñaki Baz Castillo
2009/3/27 Jacek Konieczny <[hidden email]>:
> Hello,
>
> On Wed, Mar 25, 2009 at 09:36:42AM -0400, Jeff Pyle wrote:
>>  I woke up this morning to this on Opensips 1.5.0:
>
> Core was generated by `opensips'.
> Program terminated with signal 11, Segmentation fault.
> #0  0xb7b673bd in mysql_stmt_result_metadata (stmt=0x828cb50) at libmysql.c:2254
> 2254      result->methods=      stmt->mysql->methods;

This issue was close but I've also experimented it in the latest version:
  https://sourceforge.net/tracker/?func=detail&aid=2713968&group_id=232389&atid=1086410


--
Iñaki Baz Castillo
<[hidden email]>

_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Jacek Konieczny
On Fri, Mar 27, 2009 at 11:53:47AM +0100, Iñaki Baz Castillo wrote:

> 2009/3/27 Jacek Konieczny <[hidden email]>:
> > Hello,
> >
> > On Wed, Mar 25, 2009 at 09:36:42AM -0400, Jeff Pyle wrote:
> >>  I woke up this morning to this on Opensips 1.5.0:
> >
> > Core was generated by `opensips'.
> > Program terminated with signal 11, Segmentation fault.
> > #0  0xb7b673bd in mysql_stmt_result_metadata (stmt=0x828cb50) at libmysql.c:2254
> > 2254      result->methods=      stmt->mysql->methods;
>
> This issue was close but I've also experimented it in the latest version:
>   https://sourceforge.net/tracker/?func=detail&aid=2713968&group_id=232389&atid=1086410

No I know how to reproduce it: just restart MySQL while opensips is
running. It will soon crash at the very same place.

Greets,
        Jacek

_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Bogdan-Andrei Iancu
Hi Jacek,

but are you using the latest SVN trunk or 1.5 ?

if yes, and still crashes, could you run in debug=6 and send me the logs
(from startup to crash) ? maybe off list if the file is too big.

Thanks and regards,
Bogdan

Jacek Konieczny wrote:

> On Fri, Mar 27, 2009 at 11:53:47AM +0100, Iñaki Baz Castillo wrote:
>  
>> 2009/3/27 Jacek Konieczny <[hidden email]>:
>>    
>>> Hello,
>>>
>>> On Wed, Mar 25, 2009 at 09:36:42AM -0400, Jeff Pyle wrote:
>>>      
>>>>  I woke up this morning to this on Opensips 1.5.0:
>>>>        
>>> Core was generated by `opensips'.
>>> Program terminated with signal 11, Segmentation fault.
>>> #0  0xb7b673bd in mysql_stmt_result_metadata (stmt=0x828cb50) at libmysql.c:2254
>>> 2254      result->methods=      stmt->mysql->methods;
>>>      
>> This issue was close but I've also experimented it in the latest version:
>>   https://sourceforge.net/tracker/?func=detail&aid=2713968&group_id=232389&atid=1086410
>>    
>
> No I know how to reproduce it: just restart MySQL while opensips is
> running. It will soon crash at the very same place.
>
> Greets,
>         Jacek
>
> _______________________________________________
> Users mailing list
> [hidden email]
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>
>  


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Bogdan-Andrei Iancu
In reply to this post by Jacek Konieczny
Hi Jacek,

First of all, many, many thanks for your help and support in debugging
this issue. I have to say mysql really sucks when comes to re-connect
:(... Following the logs from you I was able to reproduce the crash and
to find the cause...and eventually a fix.

So, the fix is available on SVN - update and give it another try - it
should work (at least for me it did).

Best regards,
Bogdan

Jacek Konieczny wrote:

> On Fri, Mar 27, 2009 at 11:53:47AM +0100, Iñaki Baz Castillo wrote:
>  
>> 2009/3/27 Jacek Konieczny <[hidden email]>:
>>    
>>> Hello,
>>>
>>> On Wed, Mar 25, 2009 at 09:36:42AM -0400, Jeff Pyle wrote:
>>>      
>>>>  I woke up this morning to this on Opensips 1.5.0:
>>>>        
>>> Core was generated by `opensips'.
>>> Program terminated with signal 11, Segmentation fault.
>>> #0  0xb7b673bd in mysql_stmt_result_metadata (stmt=0x828cb50) at libmysql.c:2254
>>> 2254      result->methods=      stmt->mysql->methods;
>>>      
>> This issue was close but I've also experimented it in the latest version:
>>   https://sourceforge.net/tracker/?func=detail&aid=2713968&group_id=232389&atid=1086410
>>    
>
> No I know how to reproduce it: just restart MySQL while opensips is
> running. It will soon crash at the very same place.
>
> Greets,
>         Jacek
>
> _______________________________________________
> Users mailing list
> [hidden email]
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users
>
>  


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Jacek Konieczny
On Fri, Apr 03, 2009 at 05:46:30PM +0300, Bogdan-Andrei Iancu wrote:
> So, the fix is available on SVN - update and give it another try - it
> should work (at least for me it did).

I have applied the fix to our opensips server today and, indeed, the bug
seems fixed.

Thanks a lot! I love that kind of support to Open Source projects.

Greets,
        Jacek

_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Bogdan-Andrei Iancu
In reply to this post by Jeff Pyle
Hi Jeff,

There were several fixes in this area (mysql) and I think your case
should be covered. Please update and test again.

Thanks and regards,
Bogdan

Jeff Pyle wrote:

> Hi Bogdan,
>
> Is this information helpful for you?  Am I looking at the correct item?
>
> I've had several more crashes since this one, but absolutely no hint as to
> why.  No mysql errors on these.  Nor core dumps.  It seems to happen only
> after the proxy is up for a while.  I'm attempting to run it with debug=4
> but it's hard to keep track of that much data.  Hopefully I'll catch a crash
> at debug=4 to know at least which module is causing it.
>
>
> Thanks,
> Jeff
>
>
>
> On 3/25/09 2:28 PM, "Jeff Pyle" <[hidden email]> wrote:
>
>  
>> Hi Bogdan,
>>
>> CentOS 5.2 running 2.6.18-92.1.22.el5xen.
>>
>> Mysql client lib came from Mysql's own RPM named
>> MySQL-Cluster-gpl-client-6.3.20-0.rhel5.  The file itself is called
>> libmysqlclient.so.16.0.0 or libmysqlclient_r.so.16.0.0.
>>
>> Does that answer the version question?  I'm not sure how to tell otherwise.
>>
>>
>> - Jeff
>>
>>
>>
>> On 3/25/09 2:22 PM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>>
>>    
>>> Hi Jeff,
>>>
>>> What OS are you using? and what version for mysql client lib?
>>>
>>> Thanks and regards,
>>> Bogdan
>>>
>>> Jeff Pyle wrote:
>>>      
>>>> Hello,
>>>>
>>>> I woke up this morning to this on Opensips 1.5.0:
>>>>
>>>> Mar 25 08:50:10 opensips[12439]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:50:10 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:50:18 opensips[12412]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:50:18 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:50:19 opensips[12439]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:50:19 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:50:23 opensips[12412]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:50:23 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:50:27 opensips[12412]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:50:27 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:50:27 opensips[12439]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:50:27 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:50:30 opensips[12439]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:50:30 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:50:54 opensips[12412]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:50:54 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:51:48 opensips[12439]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:51:48 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:51:55 opensips[12412]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:51:55 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:52:16 opensips[12412]:
>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>> Mar 25 08:52:16 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>> failed to delete database information
>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: child process
>>>> 12435 exited normally, status=1
>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: terminating
>>>> due to SIGCHLD
>>>> Mar 25 08:52:25 opensips[12412]: INFO:core:sig_usr: signal 15 received
>>>> Mar 25 08:52:25 opensips[12468]: INFO:core:sig_usr: signal 15 received
>>>> Mar 25 08:52:25 opensips[12437]: INFO:core:sig_usr: signal 15 received
>>>>
>>>> I have all the memory debug output from the crash as well (several
>>>> hundred lines). Unfortunately no core was generated even with Œulimit
>>>> ­c 0¹ and a working directory set.
>>>>
>>>>
>>>> - Jeff
>>>>        
>
>
>  


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Jeff Pyle
Hi Bogdan,

I've been following the conversation.  I updated on Friday.  I haven't seen
any more of the prepared statement errors, but I still have the occasional
problem connecting to a remote mysqld instance for sip_trace.  As before,
all network captures and mysqld logs show a successful a connection.  Odd.


- Jeff



On 4/6/09 8:06 AM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:

> Hi Jeff,
>
> There were several fixes in this area (mysql) and I think your case
> should be covered. Please update and test again.
>
> Thanks and regards,
> Bogdan
>
> Jeff Pyle wrote:
>> Hi Bogdan,
>>
>> Is this information helpful for you?  Am I looking at the correct item?
>>
>> I've had several more crashes since this one, but absolutely no hint as to
>> why.  No mysql errors on these.  Nor core dumps.  It seems to happen only
>> after the proxy is up for a while.  I'm attempting to run it with debug=4
>> but it's hard to keep track of that much data.  Hopefully I'll catch a crash
>> at debug=4 to know at least which module is causing it.
>>
>>
>> Thanks,
>> Jeff
>>
>>
>>
>> On 3/25/09 2:28 PM, "Jeff Pyle" <[hidden email]> wrote:
>>
>>  
>>> Hi Bogdan,
>>>
>>> CentOS 5.2 running 2.6.18-92.1.22.el5xen.
>>>
>>> Mysql client lib came from Mysql's own RPM named
>>> MySQL-Cluster-gpl-client-6.3.20-0.rhel5.  The file itself is called
>>> libmysqlclient.so.16.0.0 or libmysqlclient_r.so.16.0.0.
>>>
>>> Does that answer the version question?  I'm not sure how to tell otherwise.
>>>
>>>
>>> - Jeff
>>>
>>>
>>>
>>> On 3/25/09 2:22 PM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>>>
>>>    
>>>> Hi Jeff,
>>>>
>>>> What OS are you using? and what version for mysql client lib?
>>>>
>>>> Thanks and regards,
>>>> Bogdan
>>>>
>>>> Jeff Pyle wrote:
>>>>      
>>>>> Hello,
>>>>>
>>>>> I woke up this morning to this on Opensips 1.5.0:
>>>>>
>>>>> Mar 25 08:50:10 opensips[12439]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:50:10 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:50:18 opensips[12412]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:50:18 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:50:19 opensips[12439]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:50:19 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:50:23 opensips[12412]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:50:23 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:50:27 opensips[12412]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:50:27 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:50:27 opensips[12439]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:50:27 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:50:30 opensips[12439]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:50:30 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:50:54 opensips[12412]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:50:54 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:51:48 opensips[12439]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:51:48 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:51:55 opensips[12412]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:51:55 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:52:16 opensips[12412]:
>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>> Mar 25 08:52:16 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>> failed to delete database information
>>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: child process
>>>>> 12435 exited normally, status=1
>>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: terminating
>>>>> due to SIGCHLD
>>>>> Mar 25 08:52:25 opensips[12412]: INFO:core:sig_usr: signal 15 received
>>>>> Mar 25 08:52:25 opensips[12468]: INFO:core:sig_usr: signal 15 received
>>>>> Mar 25 08:52:25 opensips[12437]: INFO:core:sig_usr: signal 15 received
>>>>>
>>>>> I have all the memory debug output from the crash as well (several
>>>>> hundred lines). Unfortunately no core was generated even with Œulimit
>>>>> ­c 0¹ and a working directory set.
>>>>>
>>>>>
>>>>> - Jeff
>>>>>        
>>
>>
>>  
>


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Bogdan-Andrei Iancu
You mean at statup, the old problem, right?

Regards,
Bogdan

Jeff Pyle wrote:

> Hi Bogdan,
>
> I've been following the conversation.  I updated on Friday.  I haven't seen
> any more of the prepared statement errors, but I still have the occasional
> problem connecting to a remote mysqld instance for sip_trace.  As before,
> all network captures and mysqld logs show a successful a connection.  Odd.
>
>
> - Jeff
>
>
>
> On 4/6/09 8:06 AM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>
>  
>> Hi Jeff,
>>
>> There were several fixes in this area (mysql) and I think your case
>> should be covered. Please update and test again.
>>
>> Thanks and regards,
>> Bogdan
>>
>> Jeff Pyle wrote:
>>    
>>> Hi Bogdan,
>>>
>>> Is this information helpful for you?  Am I looking at the correct item?
>>>
>>> I've had several more crashes since this one, but absolutely no hint as to
>>> why.  No mysql errors on these.  Nor core dumps.  It seems to happen only
>>> after the proxy is up for a while.  I'm attempting to run it with debug=4
>>> but it's hard to keep track of that much data.  Hopefully I'll catch a crash
>>> at debug=4 to know at least which module is causing it.
>>>
>>>
>>> Thanks,
>>> Jeff
>>>
>>>
>>>
>>> On 3/25/09 2:28 PM, "Jeff Pyle" <[hidden email]> wrote:
>>>
>>>  
>>>      
>>>> Hi Bogdan,
>>>>
>>>> CentOS 5.2 running 2.6.18-92.1.22.el5xen.
>>>>
>>>> Mysql client lib came from Mysql's own RPM named
>>>> MySQL-Cluster-gpl-client-6.3.20-0.rhel5.  The file itself is called
>>>> libmysqlclient.so.16.0.0 or libmysqlclient_r.so.16.0.0.
>>>>
>>>> Does that answer the version question?  I'm not sure how to tell otherwise.
>>>>
>>>>
>>>> - Jeff
>>>>
>>>>
>>>>
>>>> On 3/25/09 2:22 PM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>>>>
>>>>    
>>>>        
>>>>> Hi Jeff,
>>>>>
>>>>> What OS are you using? and what version for mysql client lib?
>>>>>
>>>>> Thanks and regards,
>>>>> Bogdan
>>>>>
>>>>> Jeff Pyle wrote:
>>>>>      
>>>>>          
>>>>>> Hello,
>>>>>>
>>>>>> I woke up this morning to this on Opensips 1.5.0:
>>>>>>
>>>>>> Mar 25 08:50:10 opensips[12439]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:50:10 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:50:18 opensips[12412]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:50:18 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:50:19 opensips[12439]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:50:19 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:50:23 opensips[12412]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:50:23 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:50:27 opensips[12412]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:50:27 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:50:27 opensips[12439]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:50:27 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:50:30 opensips[12439]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:50:30 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:50:54 opensips[12412]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:50:54 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:51:48 opensips[12439]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:51:48 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:51:55 opensips[12412]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:51:55 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:52:16 opensips[12412]:
>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>> Mar 25 08:52:16 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>> failed to delete database information
>>>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: child process
>>>>>> 12435 exited normally, status=1
>>>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: terminating
>>>>>> due to SIGCHLD
>>>>>> Mar 25 08:52:25 opensips[12412]: INFO:core:sig_usr: signal 15 received
>>>>>> Mar 25 08:52:25 opensips[12468]: INFO:core:sig_usr: signal 15 received
>>>>>> Mar 25 08:52:25 opensips[12437]: INFO:core:sig_usr: signal 15 received
>>>>>>
>>>>>> I have all the memory debug output from the crash as well (several
>>>>>> hundred lines). Unfortunately no core was generated even with Œulimit
>>>>>> ­c 0¹ and a working directory set.
>>>>>>
>>>>>>
>>>>>> - Jeff
>>>>>>        
>>>>>>            
>>>  
>>>      
>
>
>  


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Jeff Pyle
Correct.  Exactly.


On 4/6/09 8:54 AM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:

> You mean at statup, the old problem, right?
>
> Regards,
> Bogdan
>
> Jeff Pyle wrote:
>> Hi Bogdan,
>>
>> I've been following the conversation.  I updated on Friday.  I haven't seen
>> any more of the prepared statement errors, but I still have the occasional
>> problem connecting to a remote mysqld instance for sip_trace.  As before,
>> all network captures and mysqld logs show a successful a connection.  Odd.
>>
>>
>> - Jeff
>>
>>
>>
>> On 4/6/09 8:06 AM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>>
>>  
>>> Hi Jeff,
>>>
>>> There were several fixes in this area (mysql) and I think your case
>>> should be covered. Please update and test again.
>>>
>>> Thanks and regards,
>>> Bogdan
>>>
>>> Jeff Pyle wrote:
>>>    
>>>> Hi Bogdan,
>>>>
>>>> Is this information helpful for you?  Am I looking at the correct item?
>>>>
>>>> I've had several more crashes since this one, but absolutely no hint as to
>>>> why.  No mysql errors on these.  Nor core dumps.  It seems to happen only
>>>> after the proxy is up for a while.  I'm attempting to run it with debug=4
>>>> but it's hard to keep track of that much data.  Hopefully I'll catch a
>>>> crash
>>>> at debug=4 to know at least which module is causing it.
>>>>
>>>>
>>>> Thanks,
>>>> Jeff
>>>>
>>>>
>>>>
>>>> On 3/25/09 2:28 PM, "Jeff Pyle" <[hidden email]> wrote:
>>>>
>>>>  
>>>>      
>>>>> Hi Bogdan,
>>>>>
>>>>> CentOS 5.2 running 2.6.18-92.1.22.el5xen.
>>>>>
>>>>> Mysql client lib came from Mysql's own RPM named
>>>>> MySQL-Cluster-gpl-client-6.3.20-0.rhel5.  The file itself is called
>>>>> libmysqlclient.so.16.0.0 or libmysqlclient_r.so.16.0.0.
>>>>>
>>>>> Does that answer the version question?  I'm not sure how to tell
>>>>> otherwise.
>>>>>
>>>>>
>>>>> - Jeff
>>>>>
>>>>>
>>>>>
>>>>> On 3/25/09 2:22 PM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>>>>>
>>>>>    
>>>>>        
>>>>>> Hi Jeff,
>>>>>>
>>>>>> What OS are you using? and what version for mysql client lib?
>>>>>>
>>>>>> Thanks and regards,
>>>>>> Bogdan
>>>>>>
>>>>>> Jeff Pyle wrote:
>>>>>>      
>>>>>>          
>>>>>>> Hello,
>>>>>>>
>>>>>>> I woke up this morning to this on Opensips 1.5.0:
>>>>>>>
>>>>>>> Mar 25 08:50:10 opensips[12439]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:50:10 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:50:18 opensips[12412]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:50:18 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:50:19 opensips[12439]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:50:19 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:50:23 opensips[12412]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:50:23 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:50:27 opensips[12412]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:50:27 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:50:27 opensips[12439]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:50:27 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:50:30 opensips[12439]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:50:30 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:50:54 opensips[12412]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:50:54 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:51:48 opensips[12439]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:51:48 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:51:55 opensips[12412]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:51:55 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:52:16 opensips[12412]:
>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>> Mar 25 08:52:16 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>> failed to delete database information
>>>>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: child process
>>>>>>> 12435 exited normally, status=1
>>>>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: terminating
>>>>>>> due to SIGCHLD
>>>>>>> Mar 25 08:52:25 opensips[12412]: INFO:core:sig_usr: signal 15 received
>>>>>>> Mar 25 08:52:25 opensips[12468]: INFO:core:sig_usr: signal 15 received
>>>>>>> Mar 25 08:52:25 opensips[12437]: INFO:core:sig_usr: signal 15 received
>>>>>>>
>>>>>>> I have all the memory debug output from the crash as well (several
>>>>>>> hundred lines). Unfortunately no core was generated even with Œulimit
>>>>>>> ­c 0¹ and a working directory set.
>>>>>>>
>>>>>>>
>>>>>>> - Jeff
>>>>>>>        
>>>>>>>            
>>>>  
>>>>      
>>
>>
>>  
>


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: db_mysql error, crash

Bogdan-Andrei Iancu

If a remote server, try to run an tcpdump on 3306 (mysql port) when you
start, just to see what is going one between the mysql client and server...

Regards,
Bogdan

Jeff Pyle wrote:

> Correct.  Exactly.
>
>
> On 4/6/09 8:54 AM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>
>  
>> You mean at statup, the old problem, right?
>>
>> Regards,
>> Bogdan
>>
>> Jeff Pyle wrote:
>>    
>>> Hi Bogdan,
>>>
>>> I've been following the conversation.  I updated on Friday.  I haven't seen
>>> any more of the prepared statement errors, but I still have the occasional
>>> problem connecting to a remote mysqld instance for sip_trace.  As before,
>>> all network captures and mysqld logs show a successful a connection.  Odd.
>>>
>>>
>>> - Jeff
>>>
>>>
>>>
>>> On 4/6/09 8:06 AM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>>>
>>>  
>>>      
>>>> Hi Jeff,
>>>>
>>>> There were several fixes in this area (mysql) and I think your case
>>>> should be covered. Please update and test again.
>>>>
>>>> Thanks and regards,
>>>> Bogdan
>>>>
>>>> Jeff Pyle wrote:
>>>>    
>>>>        
>>>>> Hi Bogdan,
>>>>>
>>>>> Is this information helpful for you?  Am I looking at the correct item?
>>>>>
>>>>> I've had several more crashes since this one, but absolutely no hint as to
>>>>> why.  No mysql errors on these.  Nor core dumps.  It seems to happen only
>>>>> after the proxy is up for a while.  I'm attempting to run it with debug=4
>>>>> but it's hard to keep track of that much data.  Hopefully I'll catch a
>>>>> crash
>>>>> at debug=4 to know at least which module is causing it.
>>>>>
>>>>>
>>>>> Thanks,
>>>>> Jeff
>>>>>
>>>>>
>>>>>
>>>>> On 3/25/09 2:28 PM, "Jeff Pyle" <[hidden email]> wrote:
>>>>>
>>>>>  
>>>>>      
>>>>>          
>>>>>> Hi Bogdan,
>>>>>>
>>>>>> CentOS 5.2 running 2.6.18-92.1.22.el5xen.
>>>>>>
>>>>>> Mysql client lib came from Mysql's own RPM named
>>>>>> MySQL-Cluster-gpl-client-6.3.20-0.rhel5.  The file itself is called
>>>>>> libmysqlclient.so.16.0.0 or libmysqlclient_r.so.16.0.0.
>>>>>>
>>>>>> Does that answer the version question?  I'm not sure how to tell
>>>>>> otherwise.
>>>>>>
>>>>>>
>>>>>> - Jeff
>>>>>>
>>>>>>
>>>>>>
>>>>>> On 3/25/09 2:22 PM, "Bogdan-Andrei Iancu" <[hidden email]> wrote:
>>>>>>
>>>>>>    
>>>>>>        
>>>>>>            
>>>>>>> Hi Jeff,
>>>>>>>
>>>>>>> What OS are you using? and what version for mysql client lib?
>>>>>>>
>>>>>>> Thanks and regards,
>>>>>>> Bogdan
>>>>>>>
>>>>>>> Jeff Pyle wrote:
>>>>>>>      
>>>>>>>          
>>>>>>>              
>>>>>>>> Hello,
>>>>>>>>
>>>>>>>> I woke up this morning to this on Opensips 1.5.0:
>>>>>>>>
>>>>>>>> Mar 25 08:50:10 opensips[12439]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:50:10 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:50:18 opensips[12412]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:50:18 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:50:19 opensips[12439]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:50:19 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:50:23 opensips[12412]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:50:23 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:50:27 opensips[12412]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:50:27 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:50:27 opensips[12439]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:50:27 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:50:30 opensips[12439]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:50:30 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:50:54 opensips[12412]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:50:54 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:51:48 opensips[12439]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:51:48 opensips[12439]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:51:55 opensips[12412]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:51:55 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:52:16 opensips[12412]:
>>>>>>>> ERROR:db_mysql:db_mysql_do_prepared_query: mysql_stmt_execute()
>>>>>>>> failed(2): (1210) Incorrect arguments to mysql_stmt_execute
>>>>>>>> Mar 25 08:52:16 opensips[12412]: ERROR:dialog:remove_dialog_from_db:
>>>>>>>> failed to delete database information
>>>>>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: child process
>>>>>>>> 12435 exited normally, status=1
>>>>>>>> Mar 25 08:52:25 opensips[12400]: INFO:core:handle_sigs: terminating
>>>>>>>> due to SIGCHLD
>>>>>>>> Mar 25 08:52:25 opensips[12412]: INFO:core:sig_usr: signal 15 received
>>>>>>>> Mar 25 08:52:25 opensips[12468]: INFO:core:sig_usr: signal 15 received
>>>>>>>> Mar 25 08:52:25 opensips[12437]: INFO:core:sig_usr: signal 15 received
>>>>>>>>
>>>>>>>> I have all the memory debug output from the crash as well (several
>>>>>>>> hundred lines). Unfortunately no core was generated even with Œulimit
>>>>>>>> ­c 0¹ and a working directory set.
>>>>>>>>
>>>>>>>>
>>>>>>>> - Jeff
>>>>>>>>        
>>>>>>>>            
>>>>>>>>                
>>>>>  
>>>>>      
>>>>>          
>>>  
>>>      
>
>
>  


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users