<div dir="ltr"><div><div><div>The problem is solved.<br></div>The fix for the bug is <a href="https://github.com/OpenSIPS/opensips/commit/3c6b6930d615adb978dd2779b6cd4054c3bc49df" target="_blank"><tt>3c6b693</tt></a> ( <a href="https://github.com/OpenSIPS/opensips/commit/dbdc46c994c69b43bb124a397056268ddaf2f543" target="_blank"><tt>dbdc46c</tt></a> for 2.2 branch).<br></div><br></div>Many thanks to Ionut !<br></div><div class="gmail_extra"><br><div class="gmail_quote">On Tue, Apr 12, 2016 at 6:29 PM, Yuri Ritvin <span dir="ltr"><<a href="mailto:yuri.ritvin@gmail.com" target="_blank">yuri.ritvin@gmail.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div><div><div>It looks like some information is missing in the SIPTRACE module documentation for OpenSIPS 2.2, or apparently it bears a bug. With the given example of the configuration the log file displays these messages:<br>Â Â Â ERROR:siptrace:save_siptrace: invalid trace info<br>Â Â Â ERROR:siptrace:trace_msg_out: failed to save siptrace<br><br></div>And this is the config snippet:<br><br>#### SIPTRACE module<br>loadmodule "siptrace.so"<br>modparam("siptrace", "trace_id", "[tid]uri=mysql://opensips:opensipsrw@dbserver/opensips;table=sip_trace;")<br>modparam("siptrace", "trace_on", 1)<br><br>....<br><br>Â Â Â Â Â Â Â if (method==INVITE && !has_totag()) {<br>Â Â Â Â Â Â Â Â Â Â Â Â Â Â Â $var(user)="<a href="mailto:osips@thisnetwork.com" target="_blank">osips@thisnetwork.com</a>";<br>Â Â Â Â Â Â Â Â Â Â Â Â Â Â Â $var(trace_id) = "tid";<br>Â Â Â Â Â Â Â Â Â Â Â Â Â Â Â sip_trace("$var(trace_id)", "d", "$var(user)");<br>Â Â Â Â Â Â Â }<br><br><br></div>Then at some particular scenario SIPTRACE module crashes the OpenSIPS 2.2 application:<br>#0Â save_siptrace (info=0x7f6878bf6b58, msg=<value optimized out>, vals=0x7f68722bcf80, keys=0x7f68722bd180) at siptrace.c:878<br>Â Â Â Â Â Â Â hash = <value optimized out><br>Â Â Â Â Â Â Â it = <value optimized out><br>#1Â 0x00007f68720b5415 in trace_onreply_out (t=<value optimized out>, type=<value optimized out>, ps=0x7fff0548a820) at siptrace.c:1982<br>Â Â Â Â Â Â Â faked = <value optimized out><br>Â Â Â Â Â Â Â fromip_buff = "udp1XX.2X.XXX.185", '\000' <repeats 34 times><br>Â Â Â Â Â Â Â toip_buff = "udp1XX.2X.XXX.78", '\000' <repeats 35 times><br>Â Â Â Â Â Â Â msg = 0x7f6878c00740<br>Â Â Â Â Â Â Â to_ip = {af = 2, len = 4, u = {addrl = {1310254956, 0}, addr32 = {1310254956, 0, 0, 0}, addr16 = {59244, 19992, 0, 0, 0, 0, 0, 0}, addr = "l\347\030N", '\000' <repeats 11 times>}}<br>Â Â Â Â Â Â Â len = 3<br>Â Â Â Â Â Â Â statusbuf = "408\000\001\000\000"<br>Â Â Â Â Â Â Â sbuf = <value optimized out><br>Â Â Â Â Â Â Â dst = <value optimized out><br>Â Â Â Â Â Â Â __FUNCTION__ = "trace_onreply_out"<br>#2Â 0x00007f68748789a1 in run_trans_callbacks (type=128, trans=0x7f6878bfecc8, req=<value optimized out>, rpl=<value optimized out>, code=<value optimized out>) at t_hooks.c:209<br>Â Â Â Â Â Â Â params = {req = 0x7f6878c00740, rpl = 0xffffffffffffffff, code = 408, param = 0x7f6878bfeb28, extra1 = 0x7fff0548a9a0, extra2 = 0x7f6878bfede0}<br>Â Â Â Â Â Â Â cbp = 0x7f6878bfeb18<br>Â Â Â Â Â Â Â backup = 0x7816c0<br>Â Â Â Â Â Â Â trans_backup = 0x7f6878bfecc8<br>Â Â Â Â Â Â Â __FUNCTION__ = "run_trans_callbacks"<br>#3Â 0x00007f6874878b00 in run_trans_callbacks_locked (type=<value optimized out>, trans=0x7f6878bfecc8, req=<value optimized out>, rpl=<value optimized out>, code=<value optimized out>)<br>Â Â Â at t_hooks.c:262<br>No locals.<br>#4Â 0x00007f68748851b1 in relay_reply (t=0x7f6878bfecc8, p_msg=<value optimized out>, branch=<value optimized out>, msg_status=<value optimized out>, cancel_bitmap=0x7fff0548aa7c)<br>Â Â Â at t_reply.c:1255<br>Â Â Â Â Â Â Â relay = 0<br>Â Â Â Â Â Â Â save_clone = 0<br>Â Â Â Â Â Â Â buf = 0x7f6888aa78a8 "SIP/2.0 408 Request Timeout\r\nVia: SIP/2.0/UDP 1XX.2X.XXX.78:8408;received=1XX.2X.XXX.78;branch=z9hG4bK-524287-1---af6608797b55630d;rport=8408\r\nTo: <<a href="mailto:sip%3ATestingSubscriber1002@sip.thisnetwork.com" target="_blank">sip:TestingSubscriber1002@sip.thisnetwork.com</a>>;tag=3b8d"...<br>Â Â Â Â Â Â Â res_len = 412<br>Â Â Â Â Â Â Â relayed_code = 408<br>Â Â Â Â Â Â Â relayed_msg = 0xffffffffffffffff<br>Â Â Â Â Â Â Â bm = {to_tag_val = {<br>Â Â Â Â Â Â Â Â Â Â Â s = 0x7f6888aa796c "3b8d6d10a54b0752c937c18d274bef37-402e\r\nFrom: \"TestingSubscriber1001\"<<a href="mailto:sip%3ATestingSubscriber1001@sip.thisnetwork.com" target="_blank">sip:TestingSubscriber1001@sip.thisnetwork.com</a>>;tag=735c442d\r\nCall-ID: PKSLf7Smdwyk-hULVsGkKg..\r\nCSeq: 2 INVITE\r\nServer: SBC-185\r\nConte"..., len = 37}}<br>Â Â Â Â Â Â Â totag_retr = 0<br>Â Â Â Â Â Â Â reply_status = RPS_COMPLETED<br>Â Â Â Â Â Â Â uas_rb = 0x7f6878bfedc8<br>Â Â Â Â Â Â Â cb_s = {<br>Â Â Â Â Â Â Â Â Â s = 0x7f6888aa78a8 "SIP/2.0 408 Request Timeout\r\nVia: SIP/2.0/UDP 1XX.2X.XXX.78:8408;received=1XX.2X.XXX.78;branch=z9hG4bK-524287-1---af6608797b55630d;rport=8408\r\nTo: <<a href="mailto:sip%3ATestingSubscriber1002@sip.thisnetwork.com" target="_blank">sip:TestingSubscriber1002@sip.thisnetwork.com</a>>;tag=3b8d"..., len = 412}<br>Â Â Â Â Â Â Â text = {s = 0x50daf3 "Request Timeout", len = 15}<br>Â Â Â Â Â Â Â __FUNCTION__ = "relay_reply"<br>#5Â 0x00007f687488886e in fake_reply (ticks=70, set=<value optimized out>) at timer.c:260<br>Â Â Â Â Â Â Â cancel_bitmap = 0<br>Â Â Â Â Â Â Â do_cancel_branch = <value optimized out><br>Â Â Â Â Â Â Â reply_status = <value optimized out><br>#6Â final_response_handler (ticks=70, set=<value optimized out>) at timer.c:391<br>Â Â Â Â Â Â Â old_ctx = 0x0<br>Â Â Â Â Â Â Â my_ctx = 0x7f6888aa7f08<br>Â Â Â Â Â Â Â r_buf = 0x7f6878bfeea0<br>---Type <return> to continue, or q <return> to quit---<br>Â Â Â Â Â Â Â t = 0x7f6878bfecc8<br>#7Â timer_routine (ticks=70, set=<value optimized out>) at timer.c:1066<br>Â Â Â Â Â Â Â tl = 0x7f6878bfef18<br>Â Â Â Â Â Â Â tmp_tl = 0x0<br>Â Â Â Â Â Â Â id = <value optimized out><br>Â Â Â Â Â Â Â __FUNCTION__ = "timer_routine"<br>#8Â 0x000000000047e28b in handle_timer_job () at timer.c:632<br>Â Â Â Â Â Â Â t = 0x7f687890d760<br>Â Â Â Â Â Â Â l = <value optimized out><br>Â Â Â Â Â Â Â __FUNCTION__ = "handle_timer_job"<br>#9Â 0x00000000004ed64d in handle_io (fm=0x7f6888a9c388, idx=1, event_type=<value optimized out>) at net/net_tcp_proc.c:117<br>Â Â Â Â Â Â Â ret = 0<br>Â Â Â Â Â Â Â n = <value optimized out><br>Â Â Â Â Â Â Â con = <value optimized out><br>Â Â Â Â Â Â Â s = <value optimized out><br>Â Â Â Â Â Â Â rw = <value optimized out><br>Â Â Â Â Â Â Â resp = <value optimized out><br>Â Â Â Â Â Â Â response = {140733282036968, 140086962256117}<br>Â Â Â Â Â Â Â __FUNCTION__ = "handle_io"<br>#10 0x00000000004ee140 in io_wait_loop_epoll (repeat=0, t=2, h=<value optimized out>) at ./io_wait_loop.h:221<br>Â Â Â Â Â Â Â n = 1<br>Â Â Â Â Â Â Â r = 1<br>Â Â Â Â Â Â Â e = 0x7f6888a9c388<br>Â Â Â Â Â Â Â ret = 1<br>#11 0x00000000004ef407 in tcp_worker_proc (unix_sock=<value optimized out>) at net/net_tcp_proc.c:312<br>Â Â Â Â Â Â Â __FUNCTION__ = "tcp_worker_proc"<br>#12 0x00000000004eb31d in tcp_start_processes (chd_rank=0x7653c8, startup_done=0x0) at net/net_tcp.c:1758<br>Â Â Â Â Â Â Â r = <value optimized out><br>Â Â Â Â Â Â Â reader_fd = {92, 94}<br>Â Â Â Â Â Â Â pid = 0<br>Â Â Â Â Â Â Â si = <value optimized out><br>Â Â Â Â Â Â Â load_p = 0x7f6878bc58c8<br>Â Â Â Â Â Â Â __FUNCTION__ = "tcp_start_processes"<br>#13 0x000000000042f4af in main_loop (argc=<value optimized out>, argv=<value optimized out>) at main.c:677<br>Â Â Â Â Â Â Â startup_done = 0x0<br>Â Â Â Â Â Â Â chd_rank = 44<br>#14 main (argc=<value optimized out>, argv=<value optimized out>) at main.c:1252<br>Â Â Â Â Â Â Â cfg_stream = <value optimized out><br>Â Â Â Â Â Â Â c = <value optimized out><br>Â Â Â Â Â Â Â r = <value optimized out><br>Â Â Â Â Â Â Â tmp = 0x7fff0548bf70 ""<br>Â Â Â Â Â Â Â tmp_len = <value optimized out><br>Â Â Â Â Â Â Â port = 0<br>Â Â Â Â Â Â Â proto = 5282149<br>Â Â Â Â Â Â Â options = 0x510b48 "f:cCm:M:b:l:n:N:rRvdDFETSVhw:t:u:g:P:G:W:o:"<br>Â Â Â Â Â Â Â ret = -1<br>Â Â Â Â Â Â Â seed = 221183166<br>Â Â Â Â Â Â Â rfd = <value optimized out><br>Â Â Â Â Â Â Â __FUNCTION__ = "main"<br><br><br>This is the version print (installed via RPMs):<br>opensips -V<br>version: opensips 2.2.0-beta (x86_64/linux)<br>flags: STATS: On, DISABLE_NAGLE, USE_MCAST, SHM_MMAP, PKG_MALLOC, F_MALLOC, DBG_MALLOC, FAST_LOCK-ADAPTIVE_WAIT<br>ADAPTIVE_WAIT_LOOPS=1024, MAX_RECV_BUFFER_SIZE 262144, MAX_LISTEN 16, MAX_URI_SIZE 1024, BUF_SIZE 65535<br>poll method support: poll, epoll_lt, epoll_et, sigio_rt, select.<br>main.c compiled on 14:59:48 Mar 31 2016 with gcc 4.4.7<br><br></div>Thank you.<br></div>
</blockquote></div><br></div>