sr-dev August 2015

sr-dev@lists.kamailio.org

30 participants
191 discussions

[kamailio] kamailio 4.3 crash in update_dialog_vars_dbinfo (#301)

by MayamaTakeshi

Commit is the latest for 4.3: 48d78ce87e2f42bdc77e688a0f45ff725ea97de5 Here is the backtrace: ``` Program terminated with signal 11, Segmentation fault. #0 0x00007f3b4fd779da in update_dialog_vars_dbinfo (cell=0x7f3b3c3aa958, var=0x3433) at dlg_db_handler.c:651 651 SET_STR_VALUE(values+2, var->key); Missing separate debuginfos, use: debuginfo-install bzip2-libs-1.0.5-7.el6_0.x86_64 db4-4.7.25-19.el6_6.x86_64 elfutils-libelf-0.161-3.el6.x86_64 glibc-2.12-1.166.el6_7.1.x86_64 keyutils-libs-1.4-5.el6.x86_64 krb5-libs-1.10.3-42.el6.x86_64 libacl-2.2.49-6.el6.x86_64 libattr-2.4.44-7.el6.x86_64 libcap-2.16-5.5.el6.x86_64 libcom_err-1.41.12-22.el6.x86_64 libgcc-4.4.7-16.el6.x86_64 libselinux-2.0.94-5.8.el6.x86_64 lm_sensors-libs-3.1.1-17.el6.x86_64 lua-5.1.4-4.1.el6.x86_64 mysql-libs-5.1.73-5.el6_6.x86_64 net-snmp-libs-5.5-54.el6_7.1.x86_64 nspr-4.10.8-1.el6_6.x86_64 nss-3.19.1-3.el6_6.x86_64 nss-softokn-freebl-3.14.3-22.el6_6.x86_64 nss-util-3.19.1-1.el6_6.x86_64 openssl-1.0.1e-42.el6.x86_64 pcre-7.8-7.el6.x86_64 perl-libs-5.10.1-141.el6.x86_64 popt-1.13-7.el6.x86_64 rpm-libs-4.8.0-47.el6.x86_64 tcp_wrappers-libs-7.6-57.el6.x86_64 xz-libs-4.999.9-0.5.beta.20091007git.el6.x86_64 zlib-1.2.3-29.el6.x86_64 (gdb) bt #0 0x00007f3b4fd779da in update_dialog_vars_dbinfo (cell=0x7f3b3c3aa958, var=0x3433) at dlg_db_handler.c:651 #1 0x00007f3b4fd78511 in update_dialog_dbinfo_unsafe (cell=0x7f3b3c3aa958) at dlg_db_handler.c:718 #2 0x00007f3b4fd79afc in update_dialog_dbinfo (cell=0x7f3b3c3aa958) at dlg_db_handler.c:861 #3 0x00007f3b4fdb93dc in set_dlg_variable (dlg=0x7f3b3c3aa958, key=0x7f3b508b8760, val=0x7fff7efaae60) at dlg_var.c:305 #4 0x00007f3b50696a9c in set_start_time (dialog=0x7f3b3c3aa958) at acc_cdr.c:523 #5 0x00007f3b5069c932 in cdr_on_create (dialog=0x7f3b3c3aa958, type=2, params=0x7f3b4ffda0a0) at acc_cdr.c:790 #6 0x00007f3b4fd68398 in run_create_callbacks (dlg=0x7f3b3c3aa958, msg=0x7f3b530498f0) at dlg_cb.c:230 #7 0x00007f3b4fd82590 in dlg_new_dialog (req=0x7f3b530498f0, t=0x7f3b3c7b89a8, run_initial_cbs=1) at dlg_handlers.c:865 #8 0x00007f3b4fd81006 in dlg_onreq (t=0x7f3b3c7b89a8, type=1, param=0x7f3b52df2760) at dlg_handlers.c:717 #9 0x00007f3b52b36c3a in run_reqin_callbacks_internal (hl=0x7f3b3b7eb480, trans=0x7f3b3c7b89a8, params=0x7f3b52df2760) at t_hooks.c:360 #10 0x00007f3b52b36d48 in run_reqin_callbacks (trans=0x7f3b3c7b89a8, req=0x7f3b530498f0, code=1) at t_hooks.c:385 #11 0x00007f3b52af7b58 in build_cell (p_msg=0x7f3b530498f0) at h_table.c:356 #12 0x00007f3b52b48283 in new_t (p_msg=0x7f3b530498f0) at t_lookup.c:1269 #13 0x00007f3b52b494b3 in t_newtran (p_msg=0x7f3b530498f0) at t_lookup.c:1409 #14 0x00007f3b52b1de9c in t_relay_to (p_msg=0x7f3b530498f0, proxy=0x0, proto=0, replicate=0) at t_funcs.c:236 #15 0x00007f3b52b5cbbb in _w_t_relay_to (p_msg=0x7f3b530498f0, proxy=0x0, force_proto=0) at tm.c:1476 #16 0x00007f3b52b5dd20 in w_t_relay (p_msg=0x7f3b530498f0, _foo=0x0, _bar=0x0) at tm.c:1677 #17 0x000000000041ddad in do_action (h=0x7fff7efabc80, a=0x7f3b52efc9b8, msg=0x7f3b530498f0) at action.c:1053 #18 0x000000000042a4c3 in run_actions (h=0x7fff7efabc80, a=0x7f3b52efc9b8, msg=0x7f3b530498f0) at action.c:1548 #19 0x000000000042ab28 in run_actions_safe (h=0x7fff7efac8a0, a=0x7f3b52efc9b8, msg=0x7f3b530498f0) at action.c:1613 #20 0x0000000000543c94 in rval_get_int (h=0x7fff7efac8a0, msg=0x7f3b530498f0, i=0x7fff7efac158, rv=0x7f3b52efd008, cache=0x0) at rvalue.c:912 #21 0x0000000000547ecc in rval_expr_eval_int (h=0x7fff7efac8a0, msg=0x7f3b530498f0, res=0x7fff7efac158, rve=0x7f3b52efd000) at rvalue.c:1906 #22 0x00000000005482c2 in rval_expr_eval_int (h=0x7fff7efac8a0, msg=0x7f3b530498f0, res=0x7fff7efac5e0, rve=0x7f3b52efd700) at rvalue.c:1914 #23 0x000000000041d897 in do_action (h=0x7fff7efac8a0, a=0x7f3b52efccb0, msg=0x7f3b530498f0) at action.c:1029 #24 0x000000000042a4c3 in run_actions (h=0x7fff7efac8a0, a=0x7f3b52e45308, msg=0x7f3b530498f0) at action.c:1548 #25 0x000000000042abf0 in run_top_route (a=0x7f3b52e45308, msg=0x7f3b530498f0, c=0x0) at action.c:1634 #26 0x000000000050a938 in receive_msg ( buf=0xa6fee0 "INVITE sip:09011112222@192.168.2.185:5060 SIP/2.0\r\nVia: SIP/2.0/UDP 192.168.0.186:5010;rport;branch=z9hG4bK-13146-256700-4\r\nFrom: \"user1\" <sip:user1@test1.com>;tag=13146SIPpTag00256700\r\nTo: <sip:09011"..., len=947, rcv_info=0x7fff7efacb90) at receive.c:196 #27 0x000000000060a3ea in udp_rcv_loop () at udp_server.c:495 #28 0x00000000004a7ef7 in main_loop () at main.c:1573 #29 0x00000000004ae2d2 in main (argc=7, argv=0x7fff7efacfc8) at main.c:2533 ``` --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/301

9 years, 2 months

[kamailio] rtpengine: new read_sdp_pv parameter (#283)

by Camille Oudot

this parameter allows to specify a script var or AVP for rtpengine to get the SDP from, instead of the SIP message body. You can view, comment on, or merge this pull request online at: https://github.com/kamailio/kamailio/pull/283 -- Commit Summary -- * rtpengine: new read_sdp_pv parameter -- File Changes -- M modules/rtpengine/doc/rtpengine_admin.xml (25) M modules/rtpengine/rtpengine.c (26) -- Patch Links -- https://github.com/kamailio/kamailio/pull/283.patch https://github.com/kamailio/kamailio/pull/283.diff --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/pull/283

9 years, 2 months

[kamailio] UAC module - registration stuck in ONGOING state (does not retry) (#255)

by AndyJRobinson

I was finally able to catch an occurrence of the issue where a registration was getting stuck in the ONGOING state and not being retried. This is with 4.3.0. Here's an example of a successful registration from the logs: Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:callcentric.com> Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (_sip._udp.callcentric.com(25), 33), h=428 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (alpha19.callcentric.com(23), 1), h=201 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: <core> [forward.c:174]: get_out_socket(): socket determined: 0x7751b8e4 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 42513 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply (status): Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg(): version: <SIP/2.0> Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg(): status: <407> Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg(): reason: <Proxy Authentication Required> Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK116a.65ec1863000000000000000000000000.0>; state=16 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=16741 global id=16740 T start=0xffffffff Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: uac [auth_hdr.c:402]: build_authorization_hdr(): hdr is <Proxy-Authorization: Digest username="removed", realm="callcentric.com", nonce="75a3bdc28a8d7f8bd7531ec41ac9ea25", uri="sip:callcentric.com", response="f8dc5c3d85280da5972c1a7ef0b53f08", algorithm=MD5 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=2afe4db221460197112d59bc9a08ee82-bb3d Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:callcentric.com> Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (_sip._udp.callcentric.com(25), 33), h=428 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (alpha19.callcentric.com(23), 1), h=201 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [forward.c:174]: get_out_socket(): socket determined: 0x7751b8e4 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 42514 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_reply.c:1589]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply (status): Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg(): version: <SIP/2.0> Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg(): status: <200> Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg(): reason: <Ok> Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK216a.928c6026000000000000000000000000.0>; state=16 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=16742 global id=16741 T start=0xffffffff Sat Jul 18 23:03:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10 Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_reply.c:2053]: local_reply(): DEBUG: local transaction completed Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_hooks.c:266]: run_trans_callbacks_internal(): DBG: trans=0x76abc580, callback type 1024, id 0 entered Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: uac [uac_reg.c:723]: uac_reg_tm_callback(): completed with status 200 [uuid: removed+uac] Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0 Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_reply.c:1589]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:03:41 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Here's where the registration got stuck (I added the "ONGOING flag still set" message myself to help capture when this occurred). I see the 200 Ok response to the REGISTER with the Proxy-Authorization header, but don't see uac_reg_tm_callback being invoked. Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:callcentric.com> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (_sip._udp.callcentric.com(25), 33), h=428 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (alpha17.callcentric.com(23), 1), h=135 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: <core> [forward.c:174]: get_out_socket(): socket determined: 0x7751b8e4 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25189]: DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 42871 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply (status): Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg(): version: <SIP/2.0> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg(): status: <407> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg(): reason: <Proxy Authentication Required> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK777a.be9fe760000000000000000000000000.0>; state=16 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=16764 global id=16763 T start=0xffffffff Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: uac [auth_hdr.c:203]: parse_authenticate_body(): <stale>="TRUE" state=4 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: uac [auth_hdr.c:203]: parse_authenticate_body(): <algorithm>="MD5" state=7 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: uac [auth_hdr.c:402]: build_authorization_hdr(): hdr is <Proxy-Authorization: Digest username="removed", realm="callcentric.com", nonce="b281825597a5ca2370701bd5a71d0f7b", uri="sip:callcentric.com", response="eae092a16bc01ac1333d922d088293a5", algorithm=MD5 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=2afe4db221460197112d59bc9a08ee82-5239 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:callcentric.com> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (_sip._udp.callcentric.com(25), 33), h=428 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (alpha17.callcentric.com(23), 1), h=135 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [forward.c:174]: get_out_socket(): socket determined: 0x7751b8e4 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 42872 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_reply.c:1589]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:616]: parse_msg(): SIP Reply (status): Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:618]: parse_msg(): version: <SIP/2.0> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:620]: parse_msg(): status: <200> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:622]: parse_msg(): reason: <Ok> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK877a.1f487c81000000000000000000000000.0>; state=16 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg... Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=16765 global id=16764 T start=0xffffffff Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <t> [35]; uri=[sip:removed@callcentric.com] Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [<sip:removed@callcentric.com> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <11> <REGISTER> Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_lookup.c:888]: t_reply_matching(): DEBUG: t_reply_matching: hash 42872 label 0 branch 0 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_lookup.c:943]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x76abc580)! Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=16765 global id=16765 T end=0x76abc580 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_reply.c:2136]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=2 is_invite=0) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_reply.c:1230]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_reply.c:2016]: local_reply(): DEBUG: local_reply: branch=0, save=0, winner=0 Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_reply.c:2053]: local_reply(): DEBUG: local transaction completed Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: tm [t_reply.c:1589]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Sat Jul 18 23:18:40 2015 local0.debug /usr/sbin/kamailio[25184]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up Sat Jul 18 23:24:40 2015 local0.err /usr/sbin/kamailio[25189]: ERROR: uac [uac_reg.c:935]: uac_reg_update(): ONGOING flag still set --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/255

9 years, 3 months

[kamailio] RLS : Full state NOTIFY should be produced on first subscription (#276)

by foucse

Scenario : Loading a rls-services XML file to admin(a)example.com (XCAP HTTP PUT) : ```xml <?xml version="1.0" encoding="UTF-8"?> <rls-services> <service uri="sip:points@example.com"> <list name="points"> <entry uri="sip:point1@example.com"> <display-name>Point 1</display-name> </entry> <entry uri="sip:point2@example.com"> <display-name>Point 2</display-name> </entry> <entry uri="sip:point3@example.com"> <display-name>Point 3</display-name> </entry> </list> <packages> <package>presence</package> </packages> </service> </rls-services> ``` alice(a)example.com -> subscribes to event presence on -> point1(a)example.com bob(a)example.com -> subscribes to event presence on -> point1(a)example.com carol(a)example.com -> subscribes to event presence on -> point1(a)example.com Waiting some time (10~20 seconds, approx rls, waitn_time option)... dave(a)example.com -> subscribes to event presence on -> point1(a)example.com admin(a)example.com -> subscribes to RLS event presence.winfo for the first time on -> points(a)example.com Kamailio sends only partial state as mentioned in "fullState="false"" and "state="partial"" and only for user dave (last one subscribed) with version "5" : ```xml NOTIFY sip:admin@192.168.1.56:7060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 192.168.1.56;branch=z9hG4bKdfb9.fc98d020000000000000000000000000.0 To: <sip:admin@example.com>;tag=aoW7RQAYSw From: <sip:points@example.com>;tag=8790d89920d6a95cb6b77ece9ac70b8d-80d4 CSeq: 2 NOTIFY Call-ID: cCiV8exRxM Content-Length: 1050 User-Agent: kamailio (4.4.0-dev2 (i386/linux)) Max-Forwards: 70 Event: presence.winfo Contact: <sip:rls@192.168.1.56:5060> Subscription-State: active;expires=8 Require: eventlist Content-Type: multipart/related;type="application/rlmi+xml";start="<1438875442.sip:points@example.com.1907048652>";boundary="LQLZpLYXZgJh5s2VPxHXdN1b" --LQLZpLYXZgJh5s2VPxHXdN1b Content-Transfer-Encoding: binary Content-ID: <1438875442.sip:points@example.com.1907048652> Content-Type: application/rlmi+xml;charset="UTF-8" <?xml version="1.0"?> <list uri="sip:points@example.com" xmlns="urn:ietf:params:xml:ns:rlmi" version="2" fullState="false"><resource uri="sip:point2@example.com"><instance id="Scf8UhwQ" state="active" cid="1438875442.sip:point2@example.com.1254686349"/></resource></list> --LQLZpLYXZgJh5s2VPxHXdN1b Content-Transfer-Encoding: binary Content-ID: <1438875442.sip:point2@example.com.1254686349> Content-Type: application/watcherinfo+xml <?xml version="1.0"?> <watcherinfo xmlns="urn:ietf:params:xml:ns:watcherinfo" version="5" state="partial"> <watcher-list resource="sip:point2@example.com" package="presence"> <watcher id="alice-bob-carol-dave" event="subscribe" status="active">sip:dave@example.com</watcher> </watcher-list> </watcherinfo> --LQLZpLYXZgJh5s2VPxHXdN1b-- ``` If I am reading well RFC 4662 Section 4.5 (https://tools.ietf.org/html/rfc4662#section-4.5) Kamailio should send a full state NOTIFY for initial RLS subscribes and then it can send a partial state for updates. I think we lose information here, but if a re-subscribe is performed I get a full NOTIFY, which is my current "workaround" : 1 - Initial subscribe 2 - If full state -> OK 3 - If partial state -> re-subscribe ```xml NOTIFY sip:admin@192.168.1.56:7060;transport=tcp SIP/2.0 Via: SIP/2.0/TCP 192.168.1.56;branch=z9hG4bK00c9.e11aad40000000000000000000000000.0 To: <sip:admin@example.com>;tag=AcM3AIKdAx From: <sip:points@example.com>;tag=8790d89920d6a95cb6b77ece9ac70b8d-6573 CSeq: 1 NOTIFY Call-ID: cCiV8exRxM Content-Length: 3308 User-Agent: kamailio (4.4.0-dev2 (i386/linux)) Max-Forwards: 70 Event: presence.winfo Contact: <sip:rls@192.168.1.56:5060> Subscription-State: active;expires=17 Require: eventlist Content-Type: multipart/related;type="application/rlmi+xml";start="<1438875460.sip:points@example.com.151089623>";boundary="lxwToHs6uINUYOQjbengkND5" --lxwToHs6uINUYOQjbengkND5 Content-Transfer-Encoding: binary Content-ID: <1438875460.sip:points@example.com.151089623> Content-Type: application/rlmi+xml;charset="UTF-8" <?xml version="1.0"?> <list uri="sip:points@example.com" xmlns="urn:ietf:params:xml:ns:rlmi" version="1" fullState="true"> <resource uri="sip:point1@example.com"> <instance id="Scf8UhwQ" state="active" cid="1438875460.sip:point1@example.com.236018746"/> </resource> <resource uri="sip:point2@example.com"> <instance id="Scf8UhwQ" state="active" cid="1438875460.sip:point2@example.com.720412755"/> </resource> <resource uri="sip:point3@example.com"> <instance id="Scf8UhwQ" state="active" cid="1438875460.sip:point3@example.com.1064774448"/> </resource> </list> --lxwToHs6uINUYOQjbengkND5 Content-Transfer-Encoding: binary Content-ID: <1438875460.sip:point1@example.com.236018746> Content-Type: application/watcherinfo+xml <?xml version="1.0"?> <watcherinfo xmlns="urn:ietf:params:xml:ns:watcherinfo" version="1" state="full"> <watcher-list resource="sip:point1@example.com" package="presence"> <watcher id="796ffdf4-22384(a)192.168.1.56" event="subscribe" status="active">sip:admin@example.com</watcher> <watcher id="796ffdf4-22381(a)192.168.1.56" event="subscribe" status="active">sip:admin@example.com</watcher> </watcher-list> </watcherinfo> --lxwToHs6uINUYOQjbengkND5 Content-Transfer-Encoding: binary Content-ID: <1438875460.sip:point2@example.com.720412755> Content-Type: application/watcherinfo+xml <?xml version="1.0"?> <watcherinfo xmlns="urn:ietf:params:xml:ns:watcherinfo" version="1" state="full"> <watcher-list resource="sip:point2@example.com" package="presence"> <watcher id="796ffdf5-22384(a)192.168.1.56" event="subscribe" status="active">sip:admin@example.com</watcher> <watcher id="alice-bob-carol-dave" event="subscribe" status="active">sip:alice@example.com</watcher> <watcher id="alice-bob-carol-dave" event="subscribe" status="active">sip:bob@example.com</watcher> <watcher id="alice-bob-carol-dave" event="subscribe" status="active">sip:carol@example.com</watcher> <watcher id="alice-bob-carol-dave" event="subscribe" status="active">sip:dave@example.com</watcher> <watcher id="796ffdf5-22381(a)192.168.1.56" event="subscribe" status="active">sip:admin@example.com</watcher> </watcher-list> </watcherinfo> --lxwToHs6uINUYOQjbengkND5 Content-Transfer-Encoding: binary Content-ID: <1438875460.sip:point3@example.com.1064774448> Content-Type: application/watcherinfo+xml <?xml version="1.0"?> <watcherinfo xmlns="urn:ietf:params:xml:ns:watcherinfo" version="1" state="full"> <watcher-list resource="sip:point3@example.com" package="presence"> <watcher id="796ffdf6-22384(a)192.168.1.56" event="subscribe" status="active">sip:admin@example.com</watcher> <watcher id="796ffdf6-22381(a)192.168.1.56" event="subscribe" status="active">sip:admin@example.com</watcher> </watcher-list> </watcherinfo> --lxwToHs6uINUYOQjbengkND5-- ``` --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/276

9 years, 3 months

git:master:74c84c7c: db_postgres: Fix heap use after free error in db_postgres module

by Carsten Bock

Module: kamailio Branch: master Commit: 74c84c7cd52347fcd1c90e75dca239b5f758169b URL: https://github.com/kamailio/kamailio/commit/74c84c7cd52347fcd1c90e75dca239b… Author: Carsten Bock <carsten(a)ng-voice.com> Committer: Carsten Bock <carsten(a)ng-voice.com> Date: 2015-08-28T12:11:25+02:00 db_postgres: Fix heap use after free error in db_postgres module The result structure for a query holds a pointer returned by PQfname. When sql_do_query executes the query it gets this database result structure returned but the PQfname pointer has already been free'd by a call to db_postgres_free_query from within db_postgres_store_result. sql_do_query then tries to copy the free'd string into another result structure resulting in a heap use after free. The fix here copies the PQfname result. Fix by Chris Double --- Modified: modules/db_postgres/km_pg_con.c Modified: modules/db_postgres/km_res.c --- Diff: https://github.com/kamailio/kamailio/commit/74c84c7cd52347fcd1c90e75dca239b… Patch: https://github.com/kamailio/kamailio/commit/74c84c7cd52347fcd1c90e75dca239b… --- diff --git a/modules/db_postgres/km_pg_con.c b/modules/db_postgres/km_pg_con.c index ec98add..d053c55 100644 --- a/modules/db_postgres/km_pg_con.c +++ b/modules/db_postgres/km_pg_con.c @@ -71,10 +71,6 @@ struct pg_con* db_postgres_new_connection(struct db_id* id) memset(ptr, 0, sizeof(struct pg_con)); ptr->ref = 1; - memset(keywords, 0, (sizeof(char*) * 10)); - memset(values, 0, (sizeof(char*) * 10)); - memset(to, 0, (sizeof(char) * 16)); - if (id->port) { ports = int2str(id->port, 0); keywords[i] = "port"; diff --git a/modules/db_postgres/km_res.c b/modules/db_postgres/km_res.c index e9aa232..912206b 100644 --- a/modules/db_postgres/km_res.c +++ b/modules/db_postgres/km_res.c @@ -126,8 +126,14 @@ int db_postgres_get_columns(const db1_con_t* _h, db1_res_t* _r) RES_NAMES(_r)[col]); /* The pointer that is here returned is part of the result structure. */ - RES_NAMES(_r)[col]->s = PQfname(CON_RESULT(_h), col); - RES_NAMES(_r)[col]->len = strlen(PQfname(CON_RESULT(_h), col)); + RES_NAMES(_r)[col]->s = pkg_malloc(strlen(PQfname(CON_RESULT(_h), col))+1); + if (! RES_NAMES(_r)[col]->s) { + LM_ERR("no private memory left\n"); + db_free_columns(_r); + return -4; + } + strcpy(RES_NAMES(_r)[col]->s, PQfname(CON_RESULT(_h), col)); + RES_NAMES(_r)[col]->len = strlen(RES_NAMES(_r)[col]->s); LM_DBG("RES_NAMES(%p)[%d]=[%.*s]\n", RES_NAMES(_r)[col], col, RES_NAMES(_r)[col]->len, RES_NAMES(_r)[col]->s);

9 years, 3 months

[kamailio] jsonrpc-c notification does not free shm memory (#296)

by zxcpoiu

#### Use Case Description: I'm trying to log "user last register status" using function `jsonrpc_notification` from module `jsonrpc-c` ##### invoke in kamailio.cfg: `jsonrpc_notification("last_reg", "{'user': '$au', 'data':['$Ts', '$au', '$ct', '$ua', '$sel(received.proto_ip_port)']}");` **according to [doc](http://kamailio.org/docs/modules/4.3.x/modules/jsonrpc-c.html#idp24639… jsonrpc_notification is *asynchronous delayed job* and *do not receive response* > notifications do not receive a response. Script processing continues in the usual fashion as soon as the notification has been sent. The rpc server on localhost is implemented with Node.js which never return responses and never close connections ( it only support jsonrpc notification ) #### Observation and Test: ##### Crash occurs I found kamailio crashed periodically about 2-3 days. ( back trace attached at the end) **settings:** 1. the amount of total registered users are about 15k ~ 50k; register expiration is 1200 Secs. 2. kamailio start with parameter pkg 1G and shm 5G. 3. I had patched jsonrpc_io.c from #268 **json crash log:** ``` Aug 18 22:12:04 SIP-4 kernel: [50538943.565484] kamailio[84670]: segfault at 0 ip 00007f424a3ea3bf sp 00007fff8b3eab60 error 4 in libjson.so.0.1.0[7f424a3e6000+9000] Aug 18 22:12:23 SIP-4 /home/pkg/kamailio/sbin/kamailio[84620]: ALERT: <core> [main.c:728]: handle_sigs(): child process 84670 exited by a signal 11 Aug 18 22:12:23 SIP-4 /home/pkg/kamailio/sbin/kamailio[84620]: ALERT: <core> [main.c:731]: handle_sigs(): core was generated ``` sometimes it would not crash, but result in allocation failure. no new connections can be accept. ``` Aug 20 12:26:04 SIP-5 /home/pkg/kamailio/sbin/kamailio[181104]: ERROR: <core> [tcp_main.c:959]: tcpconn_new(): mem. allocation failure Aug 20 12:26:04 SIP-5 /home/pkg/kamailio/sbin/kamailio[181104]: ERROR: <core> [tcp_main.c:3977]: handle_new_connect(): tcpconn_new failed, closing socket ``` ##### Test use `sip-p` to start a stress test with RPS 2000 register and observing results both from `kamcmd core.shmmem` and `top` command Kamailio died quickly along with depleting shm memory. `top` shows kamailio SHR consumed 4.8G `kamcmd core.shmmem` shows **free** is less than 1MB ##### the more `jsonrpc_notification` call we've made, the quicker kamailio out of memory. #### MEMDBG switch on memdbg and shutdown kamailio to get mem status. see Memory still-in-use summary (shm): `shm_strdup` and `jsonrpc_notification` from jsonrpc_request.c ``` Aug 24 14:39:41 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: ----------------------------- Aug 24 14:39:41 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : <core> [main.c:569]: cleanup(): Memory still-in-use summary (shm): Aug 24 14:39:41 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: summarizing all alloc'ed. fragments: Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 96 size= 4608 bytes from dialog: dlg_var.c: new_dlg_var(97) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 96 size= 2016 bytes from dialog: dlg_var.c: new_dlg_var(106) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 96 size= 1152 bytes from dialog: dlg_var.c: new_dlg_var(116) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 40 size= 10568 bytes from tmx: tmx_pretran.c: tmx_check_pretran(264) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 40 size= 5520 bytes from tmx: tmx_pretran.c: tmx_check_pretran(243) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 993 size= 7960 bytes from dialog: dlg_handlers.c: dlg_get_iuid_shm_clone(279) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count=997818 size= 113547928 bytes from jsonrpc-c: jsonrpc_request.c: shm_strdup(158) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 1 size= 1024 bytes from tmx: tmx_pretran.c: tmx_init_pretran_table(90) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 3 size= 64 bytes from usrloc: udomain.c: build_stat_name(51) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 1 size= 8 bytes from <core>: pt.c: init_pt(110) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 1 size= 8 bytes from <core>: pt.c: init_pt(105) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count=498909 size= 40377480 bytes from jsonrpc-c: jsonrpc_request.c: jsonrpc_notification(135) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 1 size= 8 bytes from dialog: dlg_hash.c: init_dlg_table(269) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 1 size= 8 bytes from dialog: dlg_hash.c: init_dlg_table(262) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 1 size= 8 bytes from dialog: dlg_hash.c: init_dlg_table(257) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 3 size= 216 bytes from <core>: timer.c: register_timer(1011) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 1 size= 8 bytes from <core>: usr_avp.c: init_avps(90) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 1 size= 8 bytes from <core>: usr_avp.c: init_avps(89) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: count= 1 size= 8 bytes from <core>: mem/shm_mem.c: shm_mem_init_mallocs(179) Aug 24 14:39:42 SIP-6 /home/pkg/kamailio/sbin/kamailio[263619]: : fm_status: ----------------------------- ``` #### crash on jsonrpc back trace ``` #0 0x00007f424a3ea3bf in json_tokener_parse_ex () from /lib/x86_64-linux-gnu/libjson.so.0 No symbol table info available. #1 0x00007f424a3ebda6 in json_tokener_parse_verbose () from /lib/x86_64-linux-gnu/libjson.so.0 No symbol table info available. #2 0x00007f424a3ebdfe in json_tokener_parse () from /lib/x86_64-linux-gnu/libjson.so.0 No symbol table info available. #3 0x00007f424a5f4e1a in cmd_pipe_cb (fd=6, event=2, arg=0x7fff8b3eae60) at jsonrpc_io.c:152 cmd = 0x7f4304a93cf8 __FUNCTION__ = "cmd_pipe_cb" params = 0x1bc0aa0 payload = 0x3985b4d6 req = 0x304a9c5 json = 0x7fff8b3ead00 "0\f\274\001" ns = 0x7f4391ebdc60 "298:{ \"jsonrpc\": \"2.0\", \"method\": \"last_reg\", \"params\": { \"user\": \"597438910\", \"data\": [ \"1439907102\", \"597438910\", \"<sip:597438910@211.246.212.119:55430;transport=tcp>;+sip.instance=\\\"<urn:uuid:f1016"... bytes = 140735529528624 g = 0x7f424a1ac2f8 sent = 0 #4 0x00007f424a1af254 in event_base_loop () from /usr/lib/x86_64-linux-gnu/libevent-2.0.so.5 No symbol table info available. #5 0x00007f424a5f45ef in jsonrpc_io_child_process (cmd_pipe=6, _servers=0x7f4391aba578 "10.1.3.9:5013,3 10.1.3.10:5013,2 10.1.3.11:5013,1") at jsonrpc_io.c:92 __FUNCTION__ = "jsonrpc_io_child_process" pipe_ev = {ev_active_next = {tqe_next = 0x0, tqe_prev = 0x1bb9dc0}, ev_next = {tqe_next = 0x7f4391ebda20, tqe_prev = 0x1bc0c00}, ev_timeout_pos = {ev_next_with_common_timeout = {tqe_next = 0xffffffff, tqe_prev = 0x0}, min_heap_idx = -1}, ev_fd = 6, ev_base = 0x1bc0aa0, _ev = {ev_io = {ev_io_next = {tqe_next = 0x0, tqe_prev = 0x1bc0fd0}, ev_timeout = {tv_sec = 0, tv_usec = 0}}, ev_signal = {ev_signal_next = {tqe_next = 0x0, tqe_prev = 0x1bc0fd0}, ev_ncalls = 0, ev_pncalls = 0x0}}, ev_events = 18, ev_res = 2, ev_flags = 130, ev_pri = 0 '\000', ev_closure = 2 '\002', ev_timeout = {tv_sec = 140733193388081, tv_usec = 6631108320}, ev_callback = 0x7f424a5f4aa7 <cmd_pipe_cb>, ev_arg = 0x7fff8b3eae60} #6 0x00007f424a5fd50b in child_init (rank=0) at jsonrpc_mod.c:135 pid = 0 #7 0x0000000000596a41 in init_mod_child (m=0x7f4391ab0db8, rank=0) at sr_module.c:898 __FUNCTION__ = "init_mod_child" #8 0x0000000000596779 in init_mod_child (m=0x7f4391ab1110, rank=0) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #9 0x0000000000596d55 in init_child (rank=0) at sr_module.c:924 No locals. #10 0x00000000004aafd3 in main_loop () at main.c:1650 i = 24 pid = 84647 si = 0x0 si_desc = "udp receiver child=23 sock=38.106.58.4:5063\000n\000\000\000~/q\000\001\000\000\000\260\344\213OB\177\000\000\020\261>\213\377\177\000\000\032\264N\000\000\000\000\000`\261>\213\377\177\000\000\000\224\351\221C\177\000\000 \333\330OB\177\000\000\260\344\213OB\177\000\000$!\314\a\000\000\000\000\260\210A\000\001\000\000" nrprocs = 24 __FUNCTION__ = "main_loop" #11 0x00000000004b0b5b in main (argc=13, argv=0x7fff8b3eb3d8) at main.c:2533 cfg_stream = 0x1b1a010 c = -1 r = 0 tmp = 0x7fff8b3ecd59 "" tmp_len = 32579 port = -767281296 proto = 0 options = 0x707db8 ":f:cm:M:dVIhEeb:l:L:n:vKrRDTN:W:w:t:u:g:P:G:SQ:O:a:A:" ret = -1 seed = 189487301 rfd = 4 debug_save = 0 debug_flag = 0 dont_fork_cnt = 0 n_lst = 0x76 p = 0x7fff8b3eb2de "" __FUNCTION__ = "main" quit ``` --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/296

9 years, 3 months

Re: [sr-dev] Is there a sense in calling run_onsend() after the msg_send() function?

by Lucian Balaceanu

Hi Daniel, I must admit this run_onsend() patch for stateful replies creation was not quite a success story. However, I think it serves a practical purpose, for example in Homer tracing and could be useful for the community. Again, I propose my past solution, with some questions: 1. I am unsure if the place I introduced the run_onsend call is appropriate since the buf used for msg_send is constructed build_res_buf_from_sip_req and build_res_buf_from_sip_res calls. 2. Also, we can maybe unite these if call branches I created: send_res = msg_send(&uas_rb->dst, buf, res_len); send_res = SEND_PR_BUFFER( uas_rb, buf, res_len ); 3. Do you think a get_send_socket snippet as follows should be inserted before the /*if (onsend_route_enabled(SIP_REPLY)){*/ : if(dst.send_sock == NULL) { dst.send_sock=get_send_socket(msg, &dst.to, dst.proto); if (dst.send_sock==0){ LM_ERR("cannot forward reply\n"); } } Thank you, Lucian On 10/29/2014 02:15 PM, Daniel-Constantin Mierla wrote: > Hello Lucian, > > I applied your patch with some fixes. > > I haven't checked with stateful replies, at some point a function from > core should be used. You can go ahead and see if it works, if not, let > me know and I can look into it as well. You can follow the callbacks > for TMCB_RESPONSE_OUT or TMCB_RESPONSE_FWDED inside tm code, they > should lead to the place where a sip response is going to be sent out. > > Cheers, > Daniel > > On 27/10/14 12:51, Lucian Balaceanu wrote: >> Hello Daniel, >> >> I must admit I only saw your mail last Friday. Until the 10th of >> October I was also on vacation. I know that you actually committed >> some of the changes together with your comments on the 12th this month. >> >> I don't know if we can consider the topic of the patch closed. As far >> as I understand, the state-full replies have not been addressed, >> right? (There should be a change in the t_reply.c) I followed the >> code to the relay_reply but I did not yet come to find the send >> function. Should I pursue further? >> >> Thank you, >> Lucian Balaceanu >> >>> Hi Lucian, >>> >>> somehow I forgot to follow up on this. But we need to get sorted out >>> soon, before we release, so it works as expected with the new >>> version. See more comments inline. >>> >>> >>> On 17/09/14 18:09, Lucian Balaceanu wrote: >>>> Hi Daniel, >>>> >>>> Please forgive me for my delay in responding to your mail. >>>> Please find attached a second version of the onsend_route_reply >>>> patch (which again has some problems). As per your previous >>>> indications I did the following: >>>> >>>> *Issue1* >>>>> From performances point of view, there can be added a config >>>>> parameter to enable running of onsend_route for replies: >>>>> >>>>> onsend_route_reply = 0|1 >>>> >>>> Following >>>> http://www.asipto.com/pub/kamailio-devel-guide/#c08add_parameters I >>>> have tried to add onsend_route_reply parameter. The code compiles, >>>> but when trying to start kamailio with this parameter inside, the >>>> parsing fails with syntax errors signaling: >>>> >>>> / 0(1321) :<core> [cfg.y:3423]: yyerror_at(): parse error in config >>>> file kamailio-basic.cfg.4.1, from line 107, column 1 to line 108, >>>> column 0: syntax error >>>> 0(1321) : <core> [cfg.y:3423]: yyerror_at(): parse error in config >>>> file kamailio-basic.cfg.4.1, from line 107, column 1 to line 108, >>>> column 0: >>>> ERROR: bad config file (2 errors)/ >>> >>> The issue is: >>> >>> +<INITIAL>{ONSEND_RT_REPLY} { yylval.intval=atoi(yytext); >>> + yy_number_str=yytext; return NUMBER; } >>> >>> It should be: >>> >>> +<INITIAL>{ONSEND_RT_REPLY} { yylval.intval=atoi(yytext); >>> + yy_number_str=yytext; return ONSEND_RT_REPLY; } >>> >>>> >>>> *Issue2* >>>>> #define onsend_enabled(rtype) >>>>> (onsend_rt.rlist[DEFAULT_RT]?((rtype==SIP_REPLY)?onsend_route_reply:1):0) >>>> That is to say you see it best to take the chek for >>>> onsend_rt.list[DEFAULT_RT] from inside run_onsend() function and >>>> call this onsend_enabled(...) before the run_onsend()? >>> >>> This is to detect whether the onsend_route should be executed for >>> SIP replies. The condition being: >>> >>> - if is a sip reply and onsend_route is set and the >>> onsend_route_reply parameter is 1 >>>> >>>> *Issue3* >>>>> On the other hand, is onsend_route also executed for local >>>>> requests? I had in mind it is only for received requests that are >>>>> forwarded ... Iirc, on onsend_route, the sip message is the one >>>>> received, the outgoing content being accessible via $snd(buf). >>>>> >>>> I agree with you with taking out the locally generated requests and >>>> only left the run_onsend call in do_forward_reply function (inside >>>> forward.c). >>>> Could you point me to the reply relaying function that is called >>>> for state-full processing? >>> Stateful processing for replies is mainly done in t_reply.c from tm >>> module. At some point there should be a send buffer function call. >>> >>> Cheers, >>> Daniel >>>> >>>> Thank you and sorry again for my late answer, >>>> Lucian >>> >>> -- >>> Daniel-Constantin Mierla >>> http://twitter.com/#!/miconda -http://www.linkedin.com/in/miconda >>> >>> >> > > -- > Daniel-Constantin Mierla > http://twitter.com/#!/miconda -http://www.linkedin.com/in/miconda

9 years, 3 months

[kamailio] kamailio 4.3 db_postgres intermittent core dump (#292)

by mading087

There seems to be an intermittent issue with kamailio db_postgres module creating new TLS connection with postgresql 9.4 server. Every now and then, kamailio would crash when initiation db connection. Here is the gdb core dump output. Thanks, Thread 1 (Thread 0x7f02f2dab7c0 (LWP 1502)): #0 0x00007f02f261de84 in RSA_free () from /usr/local/ssl/lib/libcrypto.so.1.0.0 No symbol table info available. #1 0x00007f02f264810a in EVP_PKEY_free () from /usr/local/ssl/lib/libcrypto.so.1.0.0 No symbol table info available. #2 0x00007f02f2657a24 in pubkey_cb () from /usr/local/ssl/lib/libcrypto.so.1.0.0 No symbol table info available. #3 0x00007f02f265d8ee in asn1_item_combine_free () from /usr/local/ssl/lib/libcrypto.so.1.0.0 No symbol table info available. #4 0x00007f02f265dabf in ASN1_template_free () from /usr/local/ssl/lib/libcrypto.so.1.0.0 No symbol table info available. #5 0x00007f02f265d9a6 in asn1_item_combine_free () from /usr/local/ssl/lib/libcrypto.so.1.0.0 No symbol table info available. #6 0x00007f02f265dabf in ASN1_template_free () from /usr/local/ssl/lib/libcrypto.so.1.0.0 No symbol table info available. #7 0x00007f02f265d9a6 in asn1_item_combine_free () from /usr/local/ssl/lib/libcrypto.so.1.0.0 No symbol table info available. #8 0x00007f02f265daf5 in ASN1_item_free () from /usr/local/ssl/lib/libcrypto.so.1.0.0 No symbol table info available. #9 0x00007f02f2980b89 in ssl_set_pkey () from /usr/local/ssl/lib/libssl.so.1.0.0 No symbol table info available. #10 0x00007f02f2981076 in SSL_use_PrivateKey_file () from /usr/local/ssl/lib/libssl.so.1.0.0 No symbol table info available. #11 0x00007f02f2304f8a in initialize_SSL (conn=0xfe1150) at fe-secure.c:1310 buf = {st_dev = 64772, st_ino = 1331, st_nlink = 1, st_mode = 33152, st_uid = 498, st_gid = 498, __pad0 = 0, st_rdev = 0, st_size = 1704, st_blksize = 4096, st_blocks = 8, st_atim = {tv_sec = 1439918487, tv_nsec = 915125877}, st_mtim = {tv_sec = 1439918474, tv_nsec = 632125886}, st_ctim = {tv_sec = 1439918479, tv_nsec = 551125884}, __unused = {0, 0, 0}} homedir = "/var/run/kamailio\000r\000\000\000\000\000\334-r\000\000\000\000\000\200-)\357\002\177\000\000\020\320\022\357\002\177\000\000H-)\357\002\177\000\000e&T", '\000' <repeats 13 times>"\200, -)\357\002\177\000\000\000\000\000\000\000\000\000\000\200-)\357\002\177\000\000\260]A\000\000\000\000\000@\245\064\214\377\177", '\000' <repeats 18 times>"\340, g4\214\377\177\000\000\302\033T\000\000\000\000\000\260]A\000\000\000\000\000 \b&\357\002\177\000\000\260]A\000\000\000\000\000@\245\064\214\377\177\000\000\340g4\214\377\177\000\000\200-)\357\002\177\000\000\200h4\214\000\000\000\000\340\000&\357\002\177\000\000\000\000\000\000\000\000\000\000 \b&\357\002\177\000\000\260]A\000\000\000\000\000@\245\064\214\377\177\000\000\360j4\214\377\177\000\000\001\225U\000\000\000\000\000\300"... fnbuf = "/var/run/kamailio/.postgresql/postgresql.key\000\177\000\000\360c4\214\377\177\000\000\212\332a\000\000\000\000\000(\000\000\000\004\000\000\000\250\324\335\252\002\177\000\000\000`\255\252\002\177\000\000\340\000&\357\002\177\000\000\001\000\000\000\000\000\000\000\340\324\335\252\002\177\000\000\000\000\000\000\000\000\000\000\260c4\214\002\000\000\000\064\253a\000\022\001\000\000\340\000&\357\002\177", '\000' <repeats 18 times>, "\020\000\000\000\000\000\000\000\000`\255\252\003\000\000\000\004\000\000\000\000\000\000\000\360c4\214\377\177\000\000g\002\000\000\000\000\000\000\030d\324\352\002\177\000\000\000\000\000\000\000\000\000\000\020d4\214\377\177\000\000\352\252a\000\022\001\000\000\030d\324\352\002\177\000\000\340\065\324\352\004\000\000\000"... sebuf = "|Ak\362\002\177\000\000\002\000\000\000\n\000\000\000@k4\214\377\177\000\000\020\" \253\002\177\000\000\000\000\000\000\000\000\000\000\240r\333\252\002\177\000\000p\" \253\002\177\000\000p\" \253\264\001\000\000|Ak\362\002\177\000\000\002\000\000\000\006\000\000\000\000\000\000\000\377\177\000\000w\271\320\352\002\177\000\000\340\065\324\352\022\001\000\000\240r\333\252\002\177\000\000\000\000\000\000\000\000\000\000p\" \253\002\177\000\000\240}\222\362\002\177\000\000\063\364\\\362\002\177\000\000\240k4\214\377\177\000\000p\000\000\000\000\000\000\000\260k4\214\377\177\000\000\020\" \253\002\177\000\000\240}\222\362\002\177\000\000p\" \253\002\177\000\000p\024\376\000\000\000\000\000\360m4\214\377\177\000\000\020\" \253\002\177\000\000\300\005 \253\002\177\000\000\006\000\000\000\000\000\000\000p\024\376\000\000\000\000\000\020\" \253\002\177\000\000\202"... have_homedir = <value optimized out> have_cert = <value optimized out> pkey = 0x0 #12 0x00007f02f2305996 in pqsecure_open_client (conn=0xfe1150) at fe-secure.c:299 rc = <value optimized out> #13 0x00007f02f22f5566 in PQconnectPoll (conn=0xfe1150) at fe-connect.c:2203 pollres = <value optimized out> res = <value optimized out> sebuf = "\000\000\000\000\000\000\000\000@", '\000' <repeats 31 times>, "\002\000\000\000\060\000\000\000[\000\000\000|\000\000\000w\000\000\000n\000\000\000[\000\000\000|\000\000\000\360\024\376\000\000\000\000\000\002\000\000\000\000\000\000\000\200n\354\361\002\177\000\000\350\021\376\000\000\000\000\000\020\251\376\000\000\000\000\000P\021\376\000\000\000\000\000\261\026\273\361\002\177\000\000\360\024\376\000\000\000\000\000\002\000\000\000\000\000\000\000\000%Q\362\002\177\000\000\222~\273\361\002\177\000\000p\024\376\000\000\000\000\000\070\255\376\000\000\000\000\000\020\251\376\000\000\000\000\000\320\217\064\214\377\177\000\000\270\251\376\000\000\000\000\000h8f/\220;\241g\020\251\376\000\000\000\000\000\004\000\000\000\000\000\000\000\320\217\064\214\377\177\000\000\270\251\376", '\000' <repeats 13 times>, "h8f/\220;\241g" optval = 0 #14 0x00007f02f22f657e in connectDBComplete (conn=0xfe1150) at fe-connect.c:1596 flag = <value optimized out> finish_time = -1 #15 0x00007f02f22f72bf in PQconnectdbParams (keywords=<value optimized out>, values=<value optimized out>, expand_dbname=<value optimized out>) at fe-connect.c:462 conn = 0xfe1150 #16 0x00007f02eeefe8e7 in db_postgres_new_connection (id=0x7f02ef203850) at km_pg_con.c:105 ptr = 0x7f02ef26fd10 ports = 0x0 i = 4 keywords = {0x7f02eef21e24 "host", 0x7f02eef21e29 "dbname", 0x7f02eef21e30 "user", 0x7f02eef21e35 "password", 0x0, 0x7f02eeabc844 "H\213E\310H\213\020H\213E\270\213@\bH\230H\215\004\002\306", 0x7f02ef1e2a28 "postgres", 0x7f02ef1e2a28 "postgres", 0x415db0 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300\060Tn", 0x1268c34a540 <Address 0x1268c34a540 out of bounds>} values = {0x7f02ef150788 "localhost", 0x7f02ef151338 "asterisk", 0x7f02ef2009b8 "asterisk", 0x7f02ef14fa48 "password", 0x0, 0x7f02ef203850 "\230\070 \357\002\177", 0x7f02ef12d268 "", 0x0, 0x500000011 <Address 0x500000011 out of bounds>, 0x260000002f <Address 0x260000002f out of bounds>} to = "\343S\026\357\002\177\000\000\210\070 \357\002\177\000" __FUNCTION__ = "db_postgres_new_connection" #17 0x00007f02eeab7927 in db_do_init2 (url=0x7f02ef165488, new_connection=0x7f02eeefd272 <db_postgres_new_connection>, pooling=DB_POOLING_PERMITTED) at db.c:312 id = 0x7f02ef203850 con = 0x0 res = 0x7f02ef1723b0 con_size = 32 __FUNCTION__ = "db_do_init2" #18 0x00007f02eeab69b4 in db_do_init (url=0x7f02ef165488, new_connection=0x7f02eeefd272 <db_postgres_new_connection>) at db.c:265 No locals. #19 0x00007f02eeeef4f2 in db_postgres_init (_url=0x7f02ef165488) at km_dbase.c:137 No locals. #20 0x00007f02ee89ab3b in sql_connect () at sql_api.c:160 sc = 0x7f02ef165470 __FUNCTION__ = "sql_connect" #21 0x00007f02ee8a966a in child_init (rank=6) at sqlops.c:146 No locals. #22 0x0000000000594061 in init_mod_child (m=0x7f02ef151ff8, rank=6) at sr_module.c:898 __FUNCTION__ = "init_mod_child" #23 0x0000000000593d9c in init_mod_child (m=0x7f02ef152538, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #24 0x0000000000593d9c in init_mod_child (m=0x7f02ef152760, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #25 0x0000000000593d9c in init_mod_child (m=0x7f02ef152e38, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #26 0x0000000000593d9c in init_mod_child (m=0x7f02ef153400, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #27 0x0000000000593d9c in init_mod_child (m=0x7f02ef154ce0, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #28 0x0000000000593d9c in init_mod_child (m=0x7f02ef1557e8, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #29 0x0000000000593d9c in init_mod_child (m=0x7f02ef155cf8, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #30 0x0000000000593d9c in init_mod_child (m=0x7f02ef156670, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #31 0x0000000000593d9c in init_mod_child (m=0x7f02ef15b600, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #32 0x0000000000593d9c in init_mod_child (m=0x7f02ef15ba20, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #33 0x0000000000593d9c in init_mod_child (m=0x7f02ef15bcf8, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #34 0x0000000000593d9c in init_mod_child (m=0x7f02ef15c438, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #35 0x0000000000593d9c in init_mod_child (m=0x7f02ef15d120, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #36 0x0000000000593d9c in init_mod_child (m=0x7f02ef15da48, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #37 0x0000000000593d9c in init_mod_child (m=0x7f02ef15df68, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #38 0x0000000000593d9c in init_mod_child (m=0x7f02ef15e2e0, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #39 0x0000000000593d9c in init_mod_child (m=0x7f02ef15e7b8, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #40 0x0000000000593d9c in init_mod_child (m=0x7f02ef15fd30, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #41 0x0000000000593d9c in init_mod_child (m=0x7f02ef160230, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #42 0x0000000000593d9c in init_mod_child (m=0x7f02ef160568, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #43 0x0000000000593d9c in init_mod_child (m=0x7f02ef160ad8, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #44 0x0000000000593d9c in init_mod_child (m=0x7f02ef160f30, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #45 0x0000000000593d9c in init_mod_child (m=0x7f02ef162eb0, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #46 0x0000000000593d9c in init_mod_child (m=0x7f02ef163228, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #47 0x0000000000593d9c in init_mod_child (m=0x7f02ef1637b0, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #48 0x0000000000593d9c in init_mod_child (m=0x7f02ef163af0, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #49 0x0000000000593d9c in init_mod_child (m=0x7f02ef164628, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #50 0x0000000000593d9c in init_mod_child (m=0x7f02ef1649e0, rank=6) at sr_module.c:895 __FUNCTION__ = "init_mod_child" #51 0x000000000059436b in init_child (rank=6) at sr_module.c:924 No locals. #52 0x00000000004ea7d4 in fork_process (child_id=6, desc=0x7fff8c34a210 "udp receiver child=1 sock=127.0.0.1:5060", make_sock=1) at pt.c:327 pid = 0 child_process_no = 6 ret = -1 new_seed1 = 436139511 new_seed2 = 2008969640 sockfd = {19, 20} __FUNCTION__ = "fork_process" #53 0x00000000004a7cae in main_loop () at main.c:1563 i = 1 pid = 1500 si = 0x7f02ef14f4d0 si_desc = "udp receiver child=1 sock=127.0.0.1:5060\000\344\255\252\002\177\000\000\200\242\064\214\377\177\000\000+\216N\000\000\000\000\000\001\000\000\000\377\177\000\000\260\062\335\252\002\177\000\000\220a\324\352\002\177\000\000\022\000\000\000\002\177\000\000\360\065\324\352\002\177\000\000\003\000\000\000\001\000\000\000\320\242\064\214\377\177\000\000\340\020\000\020\000\000\000" nrprocs = 4 __FUNCTION__ = "main_loop" #54 0x00000000004ae302 in main (argc=11, argv=0x7fff8c34a548) at main.c:2533 cfg_stream = 0xf6b010 c = -1 r = 0 tmp = 0x7fff8c34af10 "" tmp_len = 0 port = -1 proto = 0 options = 0x704fe8 ":f:cm:M:dVIhEeb:l:L:n:vKrRDTN:W:w:t:u:g:P:G:SQ:O:a:A:" ret = -1 seed = 3091819154 rfd = 4 debug_save = 0 debug_flag = 0 dont_fork_cnt = 0 n_lst = 0x40d892 p = 0x1 <Address 0x1 out of bounds> __FUNCTION__ = "main" --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/292

9 years, 3 months

Kamailio crash on CANCEL/487

by Alex Balashov

I've encountered another crash, on 4.3:4717b5b, on processing a 487 response to a CANCEL message: Aug 21 07:03:28 server3 /usr/local/sbin/kamailio[12912]: INFO: [R-MAIN:222210534-3649154645-466773@msc9.382com.com] CANCEL received from 173.231.21.131 (RURI=sip:13147323855@199.91.66.172:5060) Aug 21 07:03:28 server3 /usr/local/sbin/kamailio[12909]: ALERT: <core> [main.c:728]: handle_sigs(): child process 12916 exited by a signal 11 Aug 21 07:03:28 server3 /usr/local/sbin/kamailio[12909]: ALERT: <core> [main.c:731]: handle_sigs(): core was generated This is similar in style to the CANCEL/487 crashes I've posted in spring and summer, except that there is no asynchronous processing and/or TM transaction suspension/resuming going on here, as I turned it off (ironically, because this resulted in occasional crashes on CANCEL/487 :-). Any help would be appreciated... (gdb) thread apply all bt full Thread 1 (Thread 0x7ff1ad71a700 (LWP 12916)): #0 0x000000000061ab7c in fm_extract_free (qm=0x7ff1ac551010, frag=0x7ff1ac803268) at mem/f_malloc.c:182 pf = 0x0 hash = 29 #1 0x000000000061c250 in fm_malloc (qm=0x7ff1ac551010, size=232, file=0x75d29c "<core>: parser/msg_parser.c", func=0x760150 "get_hdr_field", line=116) at mem/f_malloc.c:466 f = 0x7ff1ac551368 frag = 0x7ff1ac803268 hash = 33 __FUNCTION__ = "fm_malloc" #2 0x00000000006292a5 in get_hdr_field (buf=0x7ff1ac80506d "Via: SIP/2.0/UDP 173.231.21.131:5060;branch=z9hG4bK379f.13c57ed4.0\r\nRecord-Route: <sip:199.91.66.172;lr=on;ftag=sansay4054880682rdb65099;fromcor=ejFwbUZxUmpUUFNBejFwbUZxUmpUUFNBejFwbUY->\r\nTo: <sip:122"..., end=0x7ff1ac805240 "", hdr=0x7ff1ac7d5300) at parser/msg_parser.c:116 tmp = 0x7ff1ac805072 "SIP/2.0/UDP 173.231.21.131:5060;branch=z9hG4bK379f.13c57ed4.0\r\nRecord-Route: <sip:199.91.66.172;lr=on;ftag=sansay4054880682rdb65099;fromcor=ejFwbUZxUmpUUFNBejFwbUZxUmpUUFNBejFwbUY->\r\nTo: <sip:12283280"... match = 0x6f70a3 "INFO" vb = 0x7ff1ac805015 cseq_b = 0x7ff1ac805000 to_b = 0xac805004 integer = 584 err = 1453435616 uval = 0 __FUNCTION__ = "get_hdr_field" #3 0x000000000062c713 in parse_headers (msg=0x7ff1aa375e68, flags=64, next=0) at parser/msg_parser.c:334 hf = 0x7ff1ac7d5300 tmp = 0x7ff1ac80506d "Via: SIP/2.0/UDP 173.231.21.131:5060;branch=z9hG4bK379f.13c57ed4.0\r\nRecord-Route: <sip:199.91.66.172;lr=on;ftag=sansay4054880682rdb65099;fromcor=ejFwbUZxUmpUUFNBejFwbUZxUmpUUFNBejFwbUY->\r\nTo: <sip:122"... rest = 0x1fac8033e0 <Address 0x1fac8033e0 out of bounds> end = 0x7ff1ac805240 "" orig_flag = 0 __FUNCTION__ = "parse_headers" #4 0x00007ff1abc5927f in pv_get_callid (msg=0x7ff1aa375e68, param=0x7ff1ac5f0648, res=0x7fff56a1ad60) at pv_core.c:632 __FUNCTION__ = "pv_get_callid" #5 0x00000000004f7c4a in pv_get_spec_value (msg=0x7ff1aa375e68, sp=0x7ff1ac5f0630, value=0x7fff56a1ad60) at pvapi.c:1289 ret = 0 __FUNCTION__ = "pv_get_spec_value" #6 0x00007ff1aa16946c in pv_get_t_var_rpl (msg=0x7ff1ab59e040, param=0x7ff1ac5f0590, res=0x7fff56a1ad60) at t_var.c:327 pv = 0x7ff1ac5f0630 #7 0x00000000004f7c4a in pv_get_spec_value (msg=0x7ff1ab59e040, sp=0x7ff1ac5f0578, value=0x7fff56a1ad60) at pvapi.c:1289 ret = 0 __FUNCTION__ = "pv_get_spec_value" #8 0x00000000004f80b8 in pv_printf (msg=0x7ff1ab59e040, list=0x7ff1ac5f0508, buf=0x7ff1ac5667b0 "FAIL:9.2.155", len=0x7fff56a1aee8) at pvapi.c:1348 n = 5 tok = {rs = {s = 0x0, len = 0}, ri = 0, flags = 0} it = 0x7ff1ac5f0508 cur = 0x7ff1ac5667b5 "9.2.155" __FUNCTION__ = "pv_printf" #9 0x0000000000599000 in get_str_fparam (dst=0x7fff56a1aee0, msg=0x7ff1ab59e040, param=0x7ff1ac5f33f8) at sr_module.c:1672 val = {n = 8, s = {s = 0x8 <Address 0x8 out of bounds>, len = 0}, re = 0x8} ret = 32767 avp = 0x61c5e3 pv_val = {rs = {s = 0x7fff56a1ae40 "", len = 4714192}, ri = 0, flags = 0} __FUNCTION__ = "get_str_fparam" #10 0x00007ff1aa7be5d4 in w_mq_add (msg=0x7ff1ab59e040, mq=0x7ff1ac5f3388 "`Gf\254\361\177", key=0x7ff1ac5f33f8 "\260Gf\254\361\177", val=0x7ff1ac5f0780 "prf\254\361\177") at mqueue_mod.c:178 q = {s = 0x7ff1ac564778 "cdr_write1", len = 10} qkey = {s = 0x7ff1ac5667b0 "FAIL:9.2.155", len = 8192} qval = {s = 0x100000076 <Address 0x100000076 out of bounds>, len = -1539946512} __FUNCTION__ = "w_mq_add" #11 0x000000000041df7e in do_action (h=0x7fff56a1ca90, a=0x7ff1ac664290, msg=0x7ff1ab59e040) at action.c:1071 ret = -5 v = -1402577184 dst = {send_sock = 0x0, to = {s = {sa_family = 0, sa_data = "\000\000\000\000\000\000P\264\241V\377\177\000"}, sin = {sin_family = 0, sin_port = 0, sin_addr = {s_addr = 0}, sin_zero = "P\264\241V\377\177\000"}, sin6 = {sin6_family = 0, sin6_port = 0, sin6_flowinfo = 0, sin6_addr = {__in6_u = {__u6_addr8 = "P\264\241V\377\177\000\000\001\000\000\000\000\000\000", __u6_addr16 = {46160, 22177, 32767, 0, 1, 0, 0, 0}, __u6_addr32 = {1453438032, 32767, 1, 0}}}, sin6_scope_id = 1453438032}}, id = 32767, proto = -108 '\224', send_flags = {f = 60 '<', blst_imask = 84 'T'}} tmp = 0x7fff56a1ca90 "\004" new_uri = 0x0 end = 0x1 <Address 0x1 out of bounds> crt = 0x7fff56a1b410 "" cmd = 0x7ff1ac57ce20 len = 4283776 user = 0 uri = {user = {s = 0x1 <Address 0x1 out of bounds>, len = 0}, passwd = {s = 0x800000 <Address 0x800000 out of bounds>, len = -1420173248}, host = {s = 0x7ff1ac664170 "\240", len = 1453437552}, port = {s = 0x7fff56a1b750 "\020", len = 0}, params = {s = 0x1acea9045 <Address 0x1acea9045 out of bounds>, len = 140}, sip_params = {s = 0x0, len = 0}, headers = {s = 0x7fff56a1b7bc "\361\177", len = -1420173248}, port_no = 23936, proto = 65, type = ERROR_URI_T, flags = 1453447296, transport = {s = 0x0, len = 0}, ttl = {s = 0x7fff56a1b350 "P\264\241V\377\177", len = 4369192}, user_param = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = -1420173248}, maddr = {s = 0x7ff1ac664170 "\240", len = 1453443728}, method = {s = 0x0, len = 1}, lr = {s = 0x7ff1ab59e040 "\265\230!", len = -969162998}, r2 = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}, gr = {s = 0x0, len = 0}, transport_val = {s = 0x2754a248c1bbbf0a <Address 0x2754a248c1bbbf0a out of bounds>, len = -506478838}, ttl_val = {s = 0x7fff00000000 <Address 0x7fff00000000 out of bounds>, len = -1420173248}, user_param_val = {s = 0x7ff1ac665ee0 "\236", len = 1453437808}, maddr_val = {s = 0x7fff56a1b723 "12916\266\267\241V\377\177", len = 0}, method_val = {s = 0x156a1b89c <Address 0x156a1b89c out of bounds>, len = 1453439160}, lr_val = {s = 0x0, len = 1453439192}, r2_val = {s = 0x7fff56a1b300 "", len = -1420173248}, gr_val = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}} next_hop = {user = {s = 0x7fff56a1b290 "\200]A", len = 1453438216}, passwd = {s = 0x7ff1ac5dcae8 "", len = -1400908488}, host = {s = 0x1900000111 <Address 0x1900000111 out of bounds>, len = -1403153419}, port = {s = 0x7ff100000006 "", len = -1518826664}, params = {s = 0x1889a00 "PDT", len = 0}, sip_params = {s = 0x3000000010 <Address 0x3000000010 out of bounds>, len = 1453437568}, headers = {s = 0x7fff00000002 <Address 0x7fff00000002 out of bounds>, len = 4283776}, port_no = 37877, proto = 44125, type = 32753, flags = 6, transport = {s = 0x100000000 <Address 0x100000000 out of bounds>, len = -1403010800}, ttl = {s = 0x7fefa43647f0 "\031", len = -1434735464}, user_param = {s = 0x7fff56a1b1f0 "E\220\352\254\001", len = -1403510296}, maddr = {s = 0xac7fd538 <Address 0xac7fd538 out of bounds>, len = -1402575760}, method = {s = 0x7ff1ab59e040 "\265\230!", len = 1453443728}, lr = {s = 0x7ff1ac5dcae8 "", len = 4283776}, r2 = {s = 0x7fff56a1b180 "\006", len = 5209162}, gr = {s = 0x1ffb <Address 0x1ffb out of bounds>, len = -1394647486}, transport_val = {s = 0x2000 <Address 0x2000 out of bounds>, len = 0}, ttl_val = {s = 0x2010 <Address 0x2010 out of bounds>, len = -1420173248}, user_param_val = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}, maddr_val = {s = 0x0, len = 0}, method_val = {s = 0x7fff56a1b240 "P\263\241V\377\177", len = 4367555}, lr_val = {s = 0x6 <Address 0x6 out of bounds>, len = 1453439088}, r2_val = {s = 0x7ff1acea9048 "]", len = -1395039897}, gr_val = {s = 0x7ff1acea9049 "", len = 1}} u = 0x0 port = 0 dst_host = 0x7fff56a1ba30 i = 32753 flags = 0 avp = 0x7fff56a1ca90 st = {flags = 1453437200, id = 32767, name = {n = -1413079500, s = {s = 0x7ff1abc61e34 "H\213\225(\377\377\377H\211\002\213U\200H\213\205(\377\377\377\211P\020H\213\205(\377\377\377\213@\024\211\302\203\312\030H\213\205(\377\377\377\211P\024\270", len = 1453437157}, re = 0x7ff1abc61e34}, avp = 0x7fef00000000} sct = 0x1900000000 sjt = 0x200000000 rve = 0x7ff1ac5de8f0 mct = 0x7ff10000005d rv = 0x7ff1ac5d93f5 rv1 = 0x415d80 c1 = {cache_type = 2894058808, val_type = 32753, c = {avp_val = {n = 1453444832, s = {s = 0x7fff56a1cee0 "", len = -1400908488}, re = 0x7fff56a1cee0}, pval = {rs = {s = 0x7fff56a1cee0 "", len = -1400908488}, ri = -1403526944, flags = 32753}}, i2s = "\020\265\241V\377\177\000\000\002\000\000\000\000\000\000\000\350\312]\254\361\177"} s = {s = 0x1ac5de8f0 <Address 0x1ac5de8f0 out of bounds>, len = 19} srevp = {0xa876f0, 0x0} mod_f_params = {{type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NUMBER_ST, u = {number = 1, string = 0x1 <Address 0x1 out of bounds>, str = {s = 0x1 <Address 0x1 out of bounds>, len = 0}, data = 0x1, attr = 0x1, select = 0x1}}, {type = RVE_FREE_FIXUP_ST, u = {number = 0, string = 0x0, str = {s = 0x0, len = 5}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}} __FUNCTION__ = "do_action" #12 0x000000000042a4c3 in run_actions (h=0x7fff56a1ca90, a=0x7ff1ac664290, msg=0x7ff1ab59e040) at action.c:1548 t = 0x7ff1ac664290 ret = -1 mod = 0xac666478 ms = 0 __FUNCTION__ = "run_actions" #13 0x000000000041dd16 in do_action (h=0x7fff56a1ca90, a=0x7ff1ac667830, msg=0x7ff1ab59e040) at action.c:1044 ret = 1 v = 1 dst = {send_sock = 0x18c5b90, to = {s = {sa_family = 12199, sa_data = "\327U\000\000\000\000\200\272\241V\377\177\000"}, sin = {sin_family = 12199, sin_port = 21975, sin_addr = {s_addr = 0}, sin_zero = "\200\272\241V\377\177\000"}, sin6 = {sin6_family = 12199, sin6_port = 21975, sin6_flowinfo = 0, sin6_addr = {__in6_u = {__u6_addr8 = "\200\272\241V\377\177\000\000]\000\000\000\000\000\000", __u6_addr16 = {47744, 22177, 32767, 0, 93, 0, 0, 0}, __u6_addr32 = {1453439616, 32767, 93, 0}}}, sin6_scope_id = 1453439968}}, id = 32767, proto = -128 '\200', send_flags = {f = 93 ']', blst_imask = 65 'A'}} tmp = 0x0 new_uri = 0x0 end = 0x7ff1ace37090 "H\201\304", <incomplete sequence \330> crt = 0x3000000030 <Address 0x3000000030 out of bounds> cmd = 0x7fff56a1bb30 len = 1453439600 user = 32767 uri = {user = {s = 0x7fff56a1b890 "\200\036\016\255\361\177", len = 8180}, passwd = {s = 0x7ff1ad0e1e80 "", len = 25975696}, host = {s = 0x9a <Address 0x9a out of bounds>, len = 25975680}, port = {s = 0xb0 <Address 0xb0 out of bounds>, len = -1394812171}, params = {s = 0x7ff1ad0e1e80 "", len = 140}, sip_params = {s = 0x0, len = 25975088}, headers = {s = 0x7fff56a1ba00 "\220[\214\001", len = -1}, port_no = 0, proto = 0, type = ERROR_URI_T, flags = 25975088, transport = {s = 0x0, len = -1}, ttl = {s = 0x0, len = 25975088}, user_param = {s = 0x15 <Address 0x15 out of bounds>, len = -1}, maddr = {s = 0x0, len = -88}, method = {s = 0x7fff56a1ba50 "0", len = -1394381763}, lr = {s = 0x7fff56a1b9c0 "0\272\241V\377\177", len = -1445722882}, r2 = {s = 0x300000013 <Address 0x300000013 out of bounds>, len = 7}, gr = {s = 0x7300000007 <Address 0x7300000007 out of bounds>, len = 5}, transport_val = {s = 0x7ff100000001 "", len = -25200}, ttl_val = {s = 0x1889a00 "PDT", len = -1420173248}, user_param_val = {s = 0x7ff1ac7f9d50 "\230\221\\\254\361\177", len = -1400908488}, maddr_val = {s = 0x7ff1ac59d9f0 "", len = 4283776}, method_val = {s = 0x7fff56a1ba30 "\001", len = 5209162}, lr_val = {s = 0x7ff1ac5511c8 "\370\344\177\254\361\177", len = -1501168808}, r2_val = {s = 0x18c5b90 "<134>Aug 21 07:03:19 /usr/local/sbin/kamailio[12916]: INFO: <core> [mem/f_malloc.c:575]: fm_free(): freeing a free fragment (0x7ff1ac8033a8/0x7ff1ac8033e0) - ignore\n", len = 0}, gr_val = {s = 0x20007fff00000007 <Address 0x20007fff00000007 out of bounds>, len = 153}} next_hop = {user = {s = 0x7fff56a1b960 "\023", len = 1453440272}, passwd = {s = 0x56a1b7b6 <Address 0x56a1b7b6 out of bounds>, len = 0}, host = {s = 0x3631393231a1b7a0 <Address 0x3631393231a1b7a0 out of bounds>, len = 1453438902}, port = {s = 0x7ff1ad0de2e0 "E\213\352\254\361\177", len = 1453439295}, params = {s = 0x1889a00 "PDT", len = 1453438816}, sip_params = {s = 0x3000000010 <Address 0x3000000010 out of bounds>, len = 1453439312}, headers = {s = 0x7fff56a1b890 "\200\036\016\255\361\177", len = -1422518688}, port_no = 48496, proto = 22177, type = 32767, flags = 2894058808, transport = {s = 0x7ff1ac5cb420 "M", len = -1420173248}, ttl = {s = 0x7fff56a1b850 "\260", len = -1423042897}, user_param = {s = 0x0, len = -1403011424}, maddr = {s = 0x393100000200 <Address 0x393100000200 out of bounds>, len = -1400908488}, method = {s = 0xa6fec8 "487 Request cancelled\r\nVia: SIP/2.0/UDP 199.91.66.172;branch=z9hG4bK379f.ae48c4c253d913f705a8d4b04a4941ed.1\r\nVia: SIP/2.0/UDP 173.231.21.131:5060;branch=z9hG4bK379f.13c57ed4.0\r\nRecord-Route: <sip:199."..., len = -1393913790}, lr = {s = 0x18c5b9c "07:03:19 /usr/local/sbin/kamailio[12916]: INFO: <core> [mem/f_malloc.c:575]: fm_free(): freeing a free fragment (0x7ff1ac8033a8/0x7ff1ac8033e0) - ignore\n", len = 25975708}, r2 = {s = 0x7ff1ad0deac0 " \335\r\255\361\177", len = 7}, gr = {s = 0x1ffb <Address 0x1ffb out of bounds>, len = -1394647486}, transport_val = {s = 0x2000 <Address 0x2000 out of bounds>, len = 0}, ttl_val = {s = 0x2010 <Address 0x2010 out of bounds>, len = 0}, user_param_val = {s = 0x3 <Address 0x3 out of bounds>, len = 2}, maddr_val = {s = 0x3000000010 <Address 0x3000000010 out of bounds>, len = 1453439792}, method_val = {s = 0x7fff56a1ba70 "\310\364s\254\361\177", len = 25975680}, lr_val = {s = 0xb0 <Address 0xb0 out of bounds>, len = -1391583616}, r2_val = {s = 0x2010 <Address 0x2010 out of bounds>, len = 25983888}, gr_val = {s = 0x2010 <Address 0x2010 out of bounds>, len = -1394813138}} u = 0x7ff1ac7e2cb0 port = 0 dst_host = 0x7ff1ab59e040 i = 0 flags = 0 avp = 0x7ff1ac588010 st = {flags = 2730779824, id = 0, name = {n = 0, s = {s = 0x1013700000000 <Address 0x1013700000000 out of bounds>, len = 1453438901}, re = 0x1013700000000}, avp = 0x0} sct = 0x5d sjt = 0x7ff1ac7e2cb0 rve = 0x7ff1ac665670 mct = 0x7ff1ac7e2d0d rv = 0x7ff1acdf6fee rv1 = 0x7fff56a1baf0 c1 = {cache_type = 1453438912, val_type = 32767, c = {avp_val = {n = -1394501785, s = {s = 0x7ff1ace19767 "\213D$8H\201\304\070\002", len = 1453438816}, re = 0x7ff1ace19767}, pval = {rs = {s = 0x7ff1ace19767 "\213D$8H\201\304\070\002", len = 1453438816}, ri = -1403518512, flags = 32753}}, i2s = '\000' <repeats 16 times>"\220, \003\247\000\000"} s = {s = 0x1813570 "`6\201\001", len = 0} srevp = {0x0, 0x4} mod_f_params = {{type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NUMBER_ST, u = {number = 1, string = 0x1 <Address 0x1 out of bounds>, str = {s = 0x1 <Address 0x1 out of bounds>, len = 0}, data = 0x1, attr = 0x1, select = 0x1}}, {type = RVE_FREE_FIXUP_ST, u = {number = 0, string = 0x0, str = {s = 0x0, len = 5}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}} __FUNCTION__ = "do_action" #14 0x000000000042a4c3 in run_actions (h=0x7fff56a1ca90, a=0x7ff1ac6661b0, msg=0x7ff1ab59e040) at action.c:1548 t = 0x7ff1ac667830 ret = 1 mod = 0x7fff56a1d880 ms = 0 __FUNCTION__ = "run_actions" #15 0x000000000041a833 in do_action (h=0x7fff56a1ca90, a=0x7ff1ac791718, msg=0x7ff1ab59e040) at action.c:677 ret = -5 v = -1400908488 dst = {send_sock = 0xffffffffac7fd538, to = {s = {sa_family = 0, sa_data = "\000\000\000\000\000\000\030D]\254\361\177\000"}, sin = {sin_family = 0, sin_port = 0, sin_addr = {s_addr = 0}, sin_zero = "\030D]\254\361\177\000"}, sin6 = {sin6_family = 0, sin6_port = 0, sin6_flowinfo = 0, sin6_addr = {__in6_u = {__u6_addr8 = "\030D]\254\361\177\000\000\001\000\000\000\000\000\000", __u6_addr16 = {17432, 44125, 32753, 0, 1, 0, 0, 0}, __u6_addr32 = {2891793432, 32753, 1, 0}}}, sin6_scope_id = 1453441520}}, id = 32767, proto = -108 '\224', send_flags = {f = 60 '<', blst_imask = 84 'T'}} tmp = 0x7fff56a1cee0 "" new_uri = 0x0 end = 0x0 crt = 0x7fff56a1c230 "\020D]\254\361\177" cmd = 0x7ff1ac57f210 len = 1453441696 user = 32767 uri = {user = {s = 0x1ac7fd538 <Address 0x1ac7fd538 out of bounds>, len = 487}, passwd = {s = 0x0, len = -1420173248}, host = {s = 0x7ff1ac7911c0 "\305\001", len = 1453441040}, port = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 0}, params = {s = 0x100000000 <Address 0x100000000 out of bounds>, len = 0}, sip_params = {s = 0x7fff00000000 <Address 0x7fff00000000 out of bounds>, len = 4316438}, headers = {s = 0x0, len = -1420173248}, port_no = 23936, proto = 65, type = ERROR_URI_T, flags = 1453447296, transport = {s = 0x0, len = 0}, ttl = {s = 0x7fff56a1c0f0 "\360\301\241V\377\177", len = 4369192}, user_param = {s = 0x1e756a1c190 <Address 0x1e756a1c190 out of bounds>, len = -1420173248}, maddr = {s = 0x7ff1ac7911c0 "\305\001", len = 1453443728}, method = {s = 0x0, len = 1}, lr = {s = 0x7ff1ab59e040 "\265\230!", len = -579092726}, r2 = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}, gr = {s = 0x0, len = 0}, transport_val = {s = 0x2754a248dcfbbf0a <Address 0x2754a248dcfbbf0a out of bounds>, len = -506478838}, ttl_val = {s = 0x7ff100000000 "", len = 498957366}, user_param_val = {s = 0x8 <Address 0x8 out of bounds>, len = -1400894488}, maddr_val = {s = 0x7ff1ac5c4110 "\002", len = 1453442120}, method_val = {s = 0x7ff1ac5d3d20 "\023", len = -1400908488}, lr_val = {s = 0x7ff1ac7fd538 "\300\217!", len = 0}, r2_val = {s = 0x7ff1ac7fd538 "\300\217!", len = 4283776}, gr_val = {s = 0x7fff56a1c1a0 "0áV\377\177", len = 5515810}} next_hop = {user = {s = 0x12000001e7 <Address 0x12000001e7 out of bounds>, len = 1453442016}, passwd = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}, host = {s = 0x12 <Address 0x12 out of bounds>, len = 4283776}, port = {s = 0x7fff56a1bfa0 "", len = 5538764}, params = {s = 0x0, len = -1400908432}, sip_params = {s = 0x1e7ac7fd538 <Address 0x1e7ac7fd538 out of bounds>, len = 1453440880}, headers = {s = 0x7ff1ac5d2080 "", len = -1400908488}, port_no = 48768, proto = 22177, type = 32767, flags = 2881832090, transport = {s = 0x2754a248d8fbbf0a <Address 0x2754a248d8fbbf0a out of bounds>, len = 1453440880}, ttl = {s = 0x7ff1ac5d2080 "", len = -1400908488}, user_param = {s = 0x1800000000 <Address 0x1800000000 out of bounds>, len = -1403182976}, maddr = {s = 0x7fff56a1bef0 "\200]A", len = 5209162}, method = {s = 0xffffffff00000000 <Address 0xffffffff00000000 out of bounds>, len = 0}, lr = {s = 0x7ff1ac5dc308 "\022", len = -1400908488}, r2 = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}, gr = {s = 0x0, len = 0}, transport_val = {s = 0x7fff56a1bfa0 "", len = 4367555}, ttl_val = {s = 0x7ff1ac7fd538 "\300\217!", len = -1420173248}, user_param_val = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}, maddr_val = {s = 0x0, len = 0}, method_val = {s = 0x7fff56a1bfe0 "\360\300\241V\377\177", len = 4367555}, lr_val = {s = 0x7fff56a1c000 "\300\021y\254\361\177", len = -1400908488}, r2_val = {s = 0x7ff1ac5e06c0 "u", len = 1453444832}, gr_val = {s = 0x7ff1ac5d2760 "\001", len = 0}} u = 0x0 port = 0 dst_host = 0x7fff56a1c7d0 i = 19 flags = 300 avp = 0x7fff56a1ca90 st = {flags = 0, id = 32767, name = {n = 1453447296, s = {s = 0xfe56a1d880 <Address 0xfe56a1d880 out of bounds>, len = -1403227224}, re = 0xfe56a1d880}, avp = 0xac7fd538} sct = 0x0 sjt = 0x0 rve = 0x0 mct = 0x7ff1ac7fd538 rv = 0x7ff1ac5c3978 rv1 = 0x415d80 c1 = {cache_type = 1453440512, val_type = 32767, c = {avp_val = {n = -1400908488, s = {s = 0x7ff1ac7fd538 "\300\217!", len = -1403210720}, re = 0x7ff1ac7fd538}, pval = {rs = {s = 0x7ff1ac7fd538 "\300\217!", len = -1403210720}, ri = -1403539360, flags = 32753}}, i2s = "\350\375]\254\361\177\000\000\000\000\000\000\000\000\000\000\f\000\000\000\377\377"} s = {s = 0x1abc66047 <Address 0x1abc66047 out of bounds>, len = 4283776} srevp = {0x7fff56a1bda0, 0x0} mod_f_params = {{type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NUMBER_ST, u = {number = 1, string = 0x1 <Address 0x1 out of bounds>, str = {s = 0x1 <Address 0x1 out of bounds>, len = 0}, data = 0x1, attr = 0x1, select = 0x1}}, {type = RVE_FREE_FIXUP_ST, u = {number = 0, string = 0x0, str = {s = 0x0, len = 5}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}} __FUNCTION__ = "do_action" #16 0x000000000042a4c3 in run_actions (h=0x7fff56a1ca90, a=0x7ff1ac7912e0, msg=0x7ff1ab59e040) at action.c:1548 t = 0x7ff1ac791718 ret = 1 mod = 0x56a1c430 ms = 0 __FUNCTION__ = "run_actions" #17 0x000000000041dd16 in do_action (h=0x7fff56a1ca90, a=0x7ff1ac792988, msg=0x7ff1ab59e040) at action.c:1044 ret = 1 v = 1 dst = {send_sock = 0x10a, to = {s = {sa_family = 0, sa_data = "\000\000\000\000\000\000\000\000\200\000\000\000\000"}, sin = {sin_family = 0, sin_port = 0, sin_addr = {s_addr = 0}, sin_zero = "\000\000\200\000\000\000\000"}, sin6 = {sin6_family = 0, sin6_port = 0, sin6_flowinfo = 0, sin6_addr = {__in6_u = {__u6_addr8 = "\000\000\200\000\000\000\000\000\300\aU\000\000\000\000", __u6_addr16 = {0, 128, 0, 0, 1984, 85, 0, 0}, __u6_addr32 = {8388608, 0, 5572544, 0}}}, sin6_scope_id = 2331856}}, id = 0, proto = 64 '@', send_flags = {f = 248 '\370', blst_imask = 42 '*'}} tmp = 0x8 <Address 0x8 out of bounds> new_uri = 0x109 <Address 0x109 out of bounds> end = 0x7ff100000000 "" crt = 0x8556a1c8b0 <Address 0x8556a1c8b0 out of bounds> cmd = 0x7ff1ac8009c8 len = 1453443120 user = 32767 uri = {user = {s = 0x100415d80 <Address 0x100415d80 out of bounds>, len = 0}, passwd = {s = 0x7fff00000000 <Address 0x7fff00000000 out of bounds>, len = -1445744562}, host = {s = 0x10c <Address 0x10c out of bounds>, len = -1400908488}, port = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}, params = {s = 0x0, len = 0}, sip_params = {s = 0x7fff56a1cc70 "\344͡V\377\177", len = 4316527}, headers = {s = 0x8 <Address 0x8 out of bounds>, len = 0}, port_no = 51024, proto = 22177, type = 32767, flags = 6407651, transport = {s = 0x0, len = -1400878352}, ttl = {s = 0xa6ffad "54880682rdb65099;fromcor=ejFwbUZxUmpUUFNBejFwbUZxUmpUUFNBejFwbUY->\r\nTo: <sip:12283280382@216.158.87.230>;tag=2120f820bddfad05d33f3eb0c54af677-f15d\r\nFrom: <sip:4159465707@192.104.111.65>;tag=sansay4054"..., len = 10944399}, user_param = {s = 0xa701b4 "", len = -1401072656}, maddr = {s = 0x0, len = -1400908488}, method = {s = 0x7ff1ac5e0900 "v", len = 1453444832}, lr = {s = 0x762540 "parse_to_param", len = 7734992}, r2 = {s = 0x30 <Address 0x30 out of bounds>, len = -1403711472}, gr = {s = 0x7ff1ac5511c8 "\370\344\177\254\361\177", len = -1400899168}, transport_val = {s = 0x400000007 <Address 0x400000007 out of bounds>, len = 7733600}, ttl_val = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 6425939}, user_param_val = {s = 0x7fff56a1c770 "", len = -1403711472}, maddr_val = {s = 0x7fff56a1c7c0 "Д#", len = -1443563814}, method_val = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 0}, lr_val = {s = 0x800000 <Address 0x800000 out of bounds>, len = 6425939}, r2_val = {s = 0x7fff56a1c7b0 "", len = -1403711472}, gr_val = {s = 0x7fff56a1c800 "0ȡV\377\177", len = -1443563814}} next_hop = {user = {s = 0x456a1c548 <Address 0x456a1c548 out of bounds>, len = -1400908488}, passwd = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}, host = {s = 0x0, len = 0}, port = {s = 0x7fff56a1c670 "p̡V\377\177", len = 5539092}, params = {s = 0x7fff56a1c740 "pǡV\377\177", len = 5418009}, sip_params = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 0}, headers = {s = 0x800000 <Address 0x800000 out of bounds>, len = 5572896}, port_no = 37856, proto = 35, type = ERROR_URI_T, flags = 2894058808, transport = {s = 0xac5af230 <Address 0xac5af230 out of bounds>, len = 0}, ttl = {s = 0x7ff1ac7f9958 "\240\231\177\254\361\177", len = -1400908488}, user_param = {s = 0x7ff1ac7e2cb0 "[R-OUTBOUND-VENDOR-ROUTE-ADVANCE:968507771-0-290965380@192.104.111.66] Transaction cancelled\n", len = 105}, maddr = {s = 0x7fff56a1c580 "\200]A", len = 4714192}, method = {s = 0x400000000 <Address 0x400000000 out of bounds>, len = -1400923816}, lr = {s = 0x7ff1ac5d5210 "\020", len = -1400908488}, r2 = {s = 0x415d80 "1\355I\211\321^H\211\342H\203\344\360PTI\307\300", len = 1453447296}, gr = {s = 0x0, len = 0}, transport_val = {s = 0x7fff56a1c670 "p̡V\377\177", len = 4367555}, ttl_val = {s = 0x76df10 "do_parse_rr_body", len = -1400908488}, user_param_val = {s = 0xffffffff <Address 0xffffffff out of bounds>, len = -1400923816}, maddr_val = {s = 0x7ff1ac7f98c0 "", len = -1400908488}, method_val = {s = 0x7fff56a1c610 "", len = -1403711472}, lr_val = {s = 0x7fff56a1c660 "", len = -1400908488}, r2_val = {s = 0x7ff1ac5e07e0 "v", len = 1453444832}, gr_val = {s = 0x800000 <Address 0x800000 out of bounds>, len = 0}} u = 0x47eed0 port = 0 dst_host = 0x5ac551010 i = 0 flags = 32753 avp = 0x7ff100000000 st = {flags = 1453442272, id = 32767, name = {n = -1400923744, s = {s = 0x7ff1ac7f99a0 "\370][\254\361\177", len = 4283776}, re = 0x7ff1ac7f99a0}, avp = 0x1e7} sct = 0x7fff56a1c8f0 sjt = 0x61c5e3 rve = 0x7ff1ac790a38 mct = 0x13000000008 rv = 0x415d80 rv1 = 0x6f2654 c1 = {cache_type = 7303331, val_type = RV_NONE, c = {avp_val = {n = -1403486192, s = {s = 0x7ff1ac588010 "", len = 0}, re = 0x7ff1ac588010}, pval = {rs = {s = 0x7ff1ac588010 "", len = 0}, ri = 0, flags = 0}}, i2s = "\b\303]\254\361\177\000\000\000\000\000\000\000\000\000\000\360\327\177\254\361\177"} s = {s = 0x7ff1ac7f99a0 "\370][\254\361\177", len = -1400908488} srevp = {0x7fff56a1c420, 0x7ff1ac7e2cb0} mod_f_params = {{type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NUMBER_ST, u = {number = 1, string = 0x1 <Address 0x1 out of bounds>, str = {s = 0x1 <Address 0x1 out of bounds>, len = 0}, data = 0x1, attr = 0x1, select = 0x1}}, {type = RVE_FREE_FIXUP_ST, u = {number = 0, string = 0x0, str = {s = 0x0, len = 5}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}, {type = NOSUBTYPE, u = {number = 0, string = 0x0, str = {s = 0x0, len = 0}, data = 0x0, attr = 0x0, select = 0x0}}} __FUNCTION__ = "do_action" #18 0x000000000042a4c3 in run_actions (h=0x7fff56a1ca90, a=0x7ff1ac792988, msg=0x7ff1ab59e040) at action.c:1548 t = 0x7ff1ac792988 ret = -1 mod = 0x61c5e3 ms = 0 __FUNCTION__ = "run_actions" #19 0x000000000042abf0 in run_top_route (a=0x7ff1ac792988, msg=0x7ff1ab59e040, c=0x0) at action.c:1634 ctx = {rec_lev = 4, run_flags = 0, last_retcode = 1, jmp_env = {{__jmpbuf = {140675957904696, 2834068496558309130, 4283776, 140734646835328, 0, 0, 2834068496482811658, -2834435909128044790}, __mask_was_saved = 0, __saved_mask = {__val = {140675957740528, 10944776, 10944698, 10944706, 140675936383543, 2874794672, 140734646831936, 4669992, 0, 140667267710216, 140667267710224, 18446744070868028288, 11010080, 10043378560, 140675938639936, 0}}}}} p = 0x7fff56a1ca90 ret = -1400908488 sfbk = 0 #20 0x00007ff1ab320c7a in run_failure_handlers (t=0x7fefa685fb58, rpl=0x7ff1ac7fd538, code=487, extra_flags=64) at t_reply.c:987 faked_req = {id = 2201781, pid = 12918, tval = {tv_sec = 1440165795, tv_usec = 762181}, fwd_send_flags = {f = 0 '\000', blst_imask = 0 '\000'}, rpl_send_flags = {f = 0 '\000', blst_imask = 0 '\000'}, first_line = {type = 1, flags = 1, len = 811, u = {request = {method = {s = 0x7fefa68c7400 "INVITE sip:12283280382@199.91.66.172:5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.17"..., len = 6}, uri = {s = 0x7fefa68c7407 "sip:12283280382@199.91.66.172:5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip"..., len = 794}, version = {s = 0x7fefa68c7722 "SIP/2.0\r\nVia: SIP/2.0/UDP 173.231.21.131:5060;branch=z9hG4bK379f.13c57ed4.0\r\nTo: <sip:12283280382@216.158.87.230>\r\nFrom: <sip:4159465707@192.104.111.65>;tag=sansay4054880682rdb65099\r\nCall-ID: 96850777"..., len = 7}, method_value = 1}, reply = {version = {s = 0x7fefa68c7400 "INVITE sip:12283280382@199.91.66.172:5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.17"..., len = 6}, status = {s = 0x7fefa68c7407 "sip:12283280382@199.91.66.172:5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip"..., len = 794}, reason = {s = 0x7fefa68c7722 "SIP/2.0\r\nVia: SIP/2.0/UDP 173.231.21.131:5060;branch=z9hG4bK379f.13c57ed4.0\r\nTo: <sip:12283280382@216.158.87.230>\r\nFrom: <sip:4159465707@192.104.111.65>;tag=sansay4054880682rdb65099\r\nCall-ID: 96850777"..., len = 7}, statuscode = 1}}}, via1 = 0x7fefa68c79f8, via2 = 0x0, headers = 0x7fefa68c79b8, last_header = 0x7fefa68c8130, parsed_flag = 18446744073709551615, h_via1 = 0x7fefa68c79b8, h_via2 = 0x0, callid = 0x7fefa68c7f80, to = 0x7fefa68c7b20, cseq = 0x7fefa68c7fc0, from = 0x7fefa68c7d38, contact = 0x7fefa68c8030, maxforwards = 0x7fefa68c80f0, route = 0x0, record_route = 0x0, content_type = 0x7fefa68c8070, content_length = 0x7fefa68c80b0, authorization = 0x0, expires = 0x0, proxy_auth = 0x0, supported = 0x0, require = 0x0, proxy_require = 0x0, unsupported = 0x0, allow = 0x0, event = 0x0, accept = 0x0, accept_language = 0x0, organization = 0x0, priority = 0x0, subject = 0x0, user_agent = 0x0, server = 0x0, content_disposition = 0x0, diversion = 0x0, rpid = 0x7fefa68c8130, refer_to = 0x0, session_expires = 0x0, min_se = 0x0, sipifmatch = 0x0, subscription_state = 0x0, date = 0x0, identity = 0x0, identity_info = 0x0, pai = 0x0, ppi = 0x0, path = 0x0, privacy = 0x0, body = 0x0, eoh = 0x7fefa68c78d5 "\r\nv=0\r\no=Sansay-VSXi 188 1 IN IP4 192.104.111.65\r\ns=Session Controller\r\nc=IN IP4 192.104.111.93\r\nt=0 0\r\nm=audio 25226 RTP/AVP 0 101\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101"..., unparsed = 0x7fefa68c78d5 "\r\nv=0\r\no=Sansay-VSXi 188 1 IN IP4 192.104.111.65\r\ns=Session Controller\r\nc=IN IP4 192.104.111.93\r\nt=0 0\r\nm=audio 25226 RTP/AVP 0 101\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101"..., rcv = {src_ip = {af = 2, len = 4, u = {addrl = {2199250861, 0}, addr32 = {2199250861, 0, 0, 0}, addr16 = {59309, 33557, 0, 0, 0, 0, 0, 0}, addr = "\255\347\025\203", '\000' <repeats 11 times>}}, dst_ip = {af = 2, len = 4, u = {addrl = {2890030023, 0}, addr32 = {2890030023, 0, 0, 0}, addr16 = {23495, 44098, 0, 0, 0, 0, 0, 0}, addr = "\307[B\254", '\000' <repeats 11 times>}}, src_port = 5060, dst_port = 5060, proto_reserved1 = 0, proto_reserved2 = 0, src_su = {s = {sa_family = 2, sa_data = "\023ĭ\347\025\203\000\000\000\000\000\000\000"}, sin = {sin_family = 2, sin_port = 50195, sin_addr = {s_addr = 2199250861}, sin_zero = "\000\000\000\000\000\000\000"}, sin6 = {sin6_family = 2, sin6_port = 50195, sin6_flowinfo = 2199250861, sin6_addr = {__in6_u = {__u6_addr8 = '\000' <repeats 15 times>, __u6_addr16 = {0, 0, 0, 0, 0, 0, 0, 0}, __u6_addr32 = {0, 0, 0, 0}}}, sin6_scope_id = 0}}, bind_address = 0x7ff1ac573620, proto = 1 '\001'}, buf = 0x7fefa68c7400 "INVITE sip:12283280382@199.91.66.172:5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.17"..., len = 1456, new_uri = {s = 0x7ff1ac800be8 "sip:3333#12283280382@216.158.87.228:5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172"..., len = 800}, dst_uri = {s = 0x0, len = 0}, parsed_uri_ok = 0, parsed_uri = {user = {s = 0x7fefa68c70e4 "3333#12283280382@216.158.87.228:5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;src"..., len = 16}, passwd = {s = 0x0, len = 0}, host = {s = 0x7fefa68c70f5 "216.158.87.228:5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;"..., len = 14}, port = {s = 0x7fefa68c7104 "5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66"..., len = 4}, params = {s = 0x7fefa68c7109 "srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;"..., len = 759}, sip_params = {s = 0x7ff1ac8013e1 "199.91.66.172;srcip=199.91.66.172", len = 759}, headers = {s = 0x0, len = 0}, port_no = 5060, proto = 0, type = SIP_URI_T, flags = 0, transport = {s = 0x0, len = 0}, ttl = {s = 0x0, len = 0}, user_param = {s = 0x0, len = 0}, maddr = {s = 0x0, len = 0}, method = {s = 0x0, len = 0}, lr = {s = 0x0, len = 0}, r2 = {s = 0x0, len = 0}, gr = {s = 0x0, len = 0}, transport_val = {s = 0x0, len = 0}, ttl_val = {s = 0x0, len = 0}, user_param_val = {s = 0x0, len = 0}, maddr_val = {s = 0x0, len = 0}, method_val = {s = 0x0, len = 0}, lr_val = {s = 0x0, len = 0}, r2_val = {s = 0x0, len = 0}, gr_val = {s = 0x0, len = 0}}, parsed_orig_ruri_ok = 0, parsed_orig_ruri = {user = {s = 0x0, len = 0}, passwd = {s = 0x0, len = 0}, host = {s = 0x0, len = 0}, port = {s = 0x0, len = 0}, params = {s = 0x0, len = 0}, sip_params = {s = 0x0, len = 0}, headers = {s = 0x0, len = 0}, port_no = 0, proto = 0, type = ERROR_URI_T, flags = 0, transport = {s = 0x0, len = 0}, ttl = {s = 0x0, len = 0}, user_param = {s = 0x0, len = 0}, maddr = {s = 0x0, len = 0}, method = {s = 0x0, len = 0}, lr = {s = 0x0, len = 0}, r2 = {s = 0x0, len = 0}, gr = {s = 0x0, len = 0}, transport_val = {s = 0x0, len = 0}, ttl_val = {s = 0x0, len = 0}, user_param_val = {s = 0x0, len = 0}, maddr_val = {s = 0x0, len = 0}, method_val = {s = 0x0, len = 0}, lr_val = {s = 0x0, len = 0}, r2_val = {s = 0x0, len = 0}, gr_val = {s = 0x0, len = 0}}, add_rm = 0x7fefa56aa190, body_lumps = 0x0, reply_lump = 0x0, add_to_branch_s = '\000' <repeats 57 times>, add_to_branch_len = 0, hash_index = 63859, msg_flags = 270544, flags = 0, set_global_address = {s = 0x0, len = 0}, set_global_port = {s = 0x0, len = 0}, force_send_socket = 0x0, path_vec = {s = 0x0, len = 0}, instance = {s = 0x0, len = 0}, reg_id = 0, ruid = {s = 0x0, len = 0}, location_ua = {s = 0x0, len = 0}, ldv = {flow = {decoded = 0, rcv = {src_ip = {af = 0, len = 0, u = {addrl = {0, 0}, addr32 = {0, 0, 0, 0}, addr16 = {0, 0, 0, 0, 0, 0, 0, 0}, addr = '\000' <repeats 15 times>}}, dst_ip = {af = 0, len = 0, u = {addrl = {0, 0}, addr32 = {0, 0, 0, 0}, addr16 = {0, 0, 0, 0, 0, 0, 0, 0}, addr = '\000' <repeats 15 times>}}, src_port = 0, dst_port = 0, proto_reserved1 = 0, proto_reserved2 = 0, src_su = {s = {sa_family = 0, sa_data = '\000' <repeats 13 times>}, sin = {sin_family = 0, sin_port = 0, sin_addr = {s_addr = 0}, sin_zero = "\000\000\000\000\000\000\000"}, sin6 = {sin6_family = 0, sin6_port = 0, sin6_flowinfo = 0, sin6_addr = {__in6_u = {__u6_addr8 = '\000' <repeats 15 times>, __u6_addr16 = {0, 0, 0, 0, 0, 0, 0, 0}, __u6_addr32 = {0, 0, 0, 0}}}, sin6_scope_id = 0}}, bind_address = 0x0, proto = 0 '\000'}}}} shmem_msg = 0x7fefa68c69f8 on_failure = 2 __FUNCTION__ = "run_failure_handlers" #21 0x00007ff1ab323903 in t_should_relay_response (Trans=0x7fefa685fb58, new_code=487, branch=1, should_store=0x7fff56a1cde0, should_relay=0x7fff56a1cde4, cancel_data=0x7fff56a1d030, reply=0x7ff1ac7fd538) at t_reply.c:1342 branch_cnt = 2 picked_code = 487 new_branch = 32753 inv_through = 0 extra_flags = 64 i = -1522490609 replies_dropped = 0 __FUNCTION__ = "t_should_relay_response" #22 0x00007ff1ab326488 in relay_reply (t=0x7fefa685fb58, p_msg=0x7ff1ac7fd538, branch=1, msg_status=487, cancel_data=0x7fff56a1d030, do_put_on_wait=1) at t_reply.c:1745 relay = 0 save_clone = 4283776 buf = 0x0 res_len = 0 relayed_code = 0 relayed_msg = 0x0 reply_bak = 0x7fefa48a12e0 bm = {to_tag_val = {s = 0x7fff56a1ce10 "0ΡV\377\177", len = -1422827893}} totag_retr = 0 reply_status = 32751 uas_rb = 0x7fff56a1ce30 to_tag = 0x7ff1ab316def reason = {s = 0x56a1ce00 <Address 0x56a1ce00 out of bounds>, len = 1024} onsend_params = {req = 0x7fefa2c3e000, rpl = 0x7fefa2c3ee78, param = 0xd300000000, code = -1534455072, flags = 32751, branch = 0, t_rbuf = 0x7fff56a1ce60, dst = 0x1, send_buf = {s = 0x7fefa685fd18 "\001", len = 1024}} __FUNCTION__ = "relay_reply" #23 0x00007ff1ab32aaed in reply_received (p_msg=0x7ff1ac7fd538) at t_reply.c:2430 msg_status = 487 last_uac_status = 100 ack = 0x7fefa48a12e0 "ACK sip:88880000412283280382@108.59.2.155:5060;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91.66.172;srcip=199.91."... ack_len = 1149 branch = 1 reply_status = -1403711016 onreply_route = 2 cancel_data = {cancel_bitmap = 0, reason = {cause = 0, u = {text = {s = 0x0, len = 10944776}, e2e_cancel = 0x0, packed_hdrs = {s = 0x0, len = 10944776}}}} uac = 0x7fefa685ff10 t = 0x7fefa685fb58 lack_dst = {send_sock = 0x800000, to = {s = {sa_family = 65333, sa_data = "\246\000\000\000\000\000\000\000\200\000\000\000\000"}, sin = {sin_family = 65333, sin_port = 166, sin_addr = {s_addr = 0}, sin_zero = "\000\000\200\000\000\000\000"}, sin6 = {sin6_family = 65333, sin6_port = 166, sin6_flowinfo = 0, sin6_addr = {__in6_u = {__u6_addr8 = "\000\000\200\000\000\000\000\000Фw\254\361\177\000", __u6_addr16 = {0, 128, 0, 0, 42192, 44151, 32753, 0}, __u6_addr32 = {8388608, 0, 2893522128, 32753}}}, sin6_scope_id = 2329736}}, id = 0, proto = -64 '\300', send_flags = {f = 239 '\357', blst_imask = 42 '*'}} backup_user_from = 0xa7fef0 backup_user_to = 0xa7fef8 backup_domain_from = 0xa7ff00 backup_domain_to = 0xa7ff08 backup_uri_from = 0xa7fee0 backup_uri_to = 0xa7fee8 backup_xavps = 0xa80020 replies_locked = 1 branch_ret = 0 prev_branch = -1400908456 blst_503_timeout = 32753 hf = 0x24856a1d100 onsend_params = {req = 0x7fff56a1cff0, rpl = 0x47eed0, param = 0x0, code = -1400889744, flags = 32753, branch = 0, t_rbuf = 0xa70108, dst = 0xa6fedf, send_buf = {s = 0x7fff56a1d0b0 "\350\376\247", len = 6407651}} ctx = {rec_lev = 0, run_flags = 0, last_retcode = 0, jmp_env = {{__jmpbuf = {140667234423520, 2834068496587669258, 4283776, 140734646835328, 0, 0, 2834068496646389514, -2834435909128044790}, __mask_was_saved = 0, __saved_mask = {__val = {0, 140734646833328, 6460751, 140734646833088, 140675915249370, 140734646833360, 0, 8388608, 5574856, 2329600, 2813752, 2833136, 8, 282, 140675915249370, 1400167727104}}}}} __FUNCTION__ = "reply_received" #24 0x000000000048dc54 in do_forward_reply (msg=0x7ff1ac7fd538, mode=0) at forward.c:747 new_buf = 0x0 dst = {send_sock = 0x0, to = {s = {sa_family = 0, sa_data = '\000' <repeats 13 times>}, sin = {sin_family = 0, sin_port = 0, sin_addr = {s_addr = 0}, sin_zero = "\000\000\000\000\000\000\000"}, sin6 = {sin6_family = 0, sin6_port = 0, sin6_flowinfo = 0, sin6_addr = {__in6_u = {__u6_addr8 = '\000' <repeats 15 times>, __u6_addr16 = {0, 0, 0, 0, 0, 0, 0, 0}, __u6_addr32 = {0, 0, 0, 0}}}, sin6_scope_id = 0}}, id = 0, proto = 0 '\000', send_flags = {f = 0 '\000', blst_imask = 0 '\000'}} new_len = 1 r = 0 ip = {af = 1453445712, len = 32767, u = {addrl = {6487414, 0}, addr32 = {6487414, 0, 0, 0}, addr16 = {64886, 98, 0, 0, 0, 0, 0, 0}, addr = "v\375b", '\000' <repeats 12 times>}} s = 0x32 <Address 0x32 out of bounds> len = 0 __FUNCTION__ = "do_forward_reply" #25 0x000000000048f297 in forward_reply (msg=0x7ff1ac7fd538) at forward.c:849 No locals. #26 0x000000000050b130 in receive_msg (buf=0xa6fec0 "SIP/2.0 487 Request cancelled\r\nVia: SIP/2.0/UDP 199.91.66.172;branch=z9hG4bK379f.ae48c4c253d913f705a8d4b04a4941ed.1\r\nVia: SIP/2.0/UDP 173.231.21.131:5060;branch=z9hG4bK379f.13c57ed4.0\r\nRecord-Route: <"..., len=584, rcv_info=0x7fff56a1d450) at receive.c:255 msg = 0x7ff1ac7fd538 ctx = {rec_lev = 10258744, run_flags = 0, last_retcode = 0, jmp_env = {{__jmpbuf = {0, 0, 0, 140675971621024, 1705102016513, 0, 170403418896, 10944128}, __mask_was_saved = 1453446232, __saved_mask = {__val = {140675955242848, 12884901902, 140675955242848, 4283776, 140734646835328, 140734646834112, 6309050, 5748413344, 140667204692152, 140734646834080, 6304733, 140734646834368, 170234505400, 10944128, 6304861, 140734646834448}}}}} ret = 1453446128 inb = {s = 0xa6fec0 "SIP/2.0 487 Request cancelled\r\nVia: SIP/2.0/UDP 199.91.66.172;branch=z9hG4bK379f.ae48c4c253d913f705a8d4b04a4941ed.1\r\nVia: SIP/2.0/UDP 173.231.21.131:5060;branch=z9hG4bK379f.13c57ed4.0\r\nRecord-Route: <"..., len = 584} __FUNCTION__ = "receive_msg" #27 0x000000000060a3ea in udp_rcv_loop () at udp_server.c:495 len = 584 buf = "SIP/2.0 487 Request cancelled\r\nVia: SIP/2.0/UDP 199.91.66.172;branch=z9hG4bK379f.ae48c4c253d913f705a8d4b04a4941ed.1\r\nVia: SIP/2.0/UDP 173.231.21.131:5060;branch=z9hG4bK379f.13c57ed4.0\r\nRecord-Route: <"... tmp = 0xa6fe80 "75.135.118.221" from = 0x7ff1ac77a280 fromlen = 16 ri = {src_ip = {af = 2, len = 4, u = {addrl = {2600614764, 0}, addr32 = {2600614764, 0, 0, 0}, addr16 = {15212, 39682, 0, 0, 0, 0, 0, 0}, addr = "l;\002\233", '\000' <repeats 11 times>}}, dst_ip = {af = 2, len = 4, u = {addrl = {2890030023, 0}, addr32 = {2890030023, 0, 0, 0}, addr16 = {23495, 44098, 0, 0, 0, 0, 0, 0}, addr = "\307[B\254", '\000' <repeats 11 times>}}, src_port = 5060, dst_port = 5060, proto_reserved1 = 0, proto_reserved2 = 0, src_su = {s = {sa_family = 2, sa_data = "\023\304l;\002\233\000\000\000\000\000\000\000"}, sin = {sin_family = 2, sin_port = 50195, sin_addr = {s_addr = 2600614764}, sin_zero = "\000\000\000\000\000\000\000"}, sin6 = {sin6_family = 2, sin6_port = 50195, sin6_flowinfo = 2600614764, sin6_addr = {__in6_u = {__u6_addr8 = '\000' <repeats 15 times>, __u6_addr16 = {0, 0, 0, 0, 0, 0, 0, 0}, __u6_addr32 = {0, 0, 0, 0}}}, sin6_scope_id = 0}}, bind_address = 0x7ff1ac573620, proto = 1 '\001'} __FUNCTION__ = "udp_rcv_loop" #28 0x00000000004a7ef7 in main_loop () at main.c:1573 i = 6 pid = 0 si = 0x7ff1ac573620 si_desc = "udp receiver child=6 sock=199.91.66.172:5060\000\177\000\000\300աV\377\177\000\000\373\215N\000\000\000\000\000\023\b\000\000\377\177\000\000\260dĢ\357\177\000\000p֡V\377\177\000\000\260dĢ\357\177\000\000\200]A\000\000\000\000\000\200ءV\001\000\000\000\020֡V\377\177\000\000\236\216N\000\000\000\000" nrprocs = 24 __FUNCTION__ = "main_loop" #29 0x00000000004ae2d2 in main (argc=11, argv=0x7fff56a1d888) at main.c:2533 cfg_stream = 0x17f1010 c = -1 r = 0 tmp = 0x7fff56a1f964 "" tmp_len = 32767 port = 1453447006 proto = 0 options = 0x704fb8 ":f:cm:M:dVIhEeb:l:L:n:vKrRDTN:W:w:t:u:g:P:G:SQ:O:a:A:" ret = -1 seed = 618280211 rfd = 4 debug_save = 0 debug_flag = 0 dont_fork_cnt = 0 n_lst = 0x40d864 p = 0xc2 <Address 0xc2 out of bounds> __FUNCTION__ = "main" -- Alex Balashov | Principal | Evariste Systems LLC 303 Perimeter Center North, Suite 300 Atlanta, GA 30346 United States Tel: +1-800-250-5920 (toll-free) / +1-678-954-0671 (direct) Web: http://www.evaristesys.com/, http://www.csrpswitch.com/

9 years, 3 months

[kamailio] kamctl ul show/xmlrpc ul.lookup not find valid AOR (Kamailio v4.3.1) (#264)

by 00Asgaroth00

For some registered users the kamctl ul show subscriber@domain does not find the AOR, however in a full listing it shows up, the ul.lookup rpc method has the same issue. Kamailio version is 4.3.1 For example, here is a full listing: ``` Domain:: location table=1024 records=4 max_slot=1 AOR:: subscriber1(a)domain.com Contact:: sip:subscriber1@192.168.1.238:32989;alias=212.2.160.202~37476~1;rinstance=5e040b71a89dace0;transport=UDP Q= Expires:: 45 Callid:: Q9tQDalSnHzw78gSlpDC6g.. Cseq:: 783 User-agent:: Z 3.7.30891 r30851 Received:: sip:212.2.160.202:37476 Path:: <sip:10.7.0.109;lr;received=sip:212.2.160.202:37476> State:: CS_NEW Flags:: 0 Cflag:: 64 Socket:: udp:10.7.0.175:5060 Methods:: 5087 Ruid:: uloc-55b11002-2df0-82a2 Reg-Id:: 0 Last-Keepalive:: 1437721166 Last-Modified:: 1437721166 AOR:: subscriber2(a)domain.com Contact:: sip:subscriber2@172.16.0.110:5060;alias=78.143.152.30~21183~1 Q= Expires:: 107 Callid:: 7c5fd25f-c2583f1d63761b4f099e0080f0d14465(a)172.16.0.110 Cseq:: 7 User-agent:: BFH_IE_Panasonic_KX-UT133X/01.278 (0080F0D14465) Received:: sip:78.143.152.30:21183 Path:: <sip:10.7.0.109;lr;received=sip:78.143.152.30:21183> State:: CS_NEW Flags:: 2 Cflag:: 64 Methods:: 6815 Ruid:: uloc-55b10ff9-1f87-45 Reg-Id:: 0 Last-Keepalive:: 1437721228 Last-Modified:: 1437721228 ``` if I lookup subscriber1, then kamctl ul show works: ``` # kamctl ul show subscriber1(a)domain.com Contact:: <sip:subscriber1@192.168.1.238:32989;alias=212.2.160.202~37476~1;rinstance=5e040b71a89dace0;transport=UDP>;q=;expires=21;flags=0x0;cflags=0x40;state=0;socket=<udp:10.7.0.175:5060>;methods=0x13DF;received=<sip:212.2.160.202:37476>;user_agent=<Z 3.7.30891 r30851>;path=<<sip:10.7.0.109;lr;received=sip:212.2.160.202:37476>>;reg-id=0 ``` However, if I lookup sibscriber2, then it cannot find it: ``` # kamctl ul show subscriber2(a)domain.com 404 AOR not found ``` I have masked the subscriber information, but if it is of use for you, I can send the actual data directly to you. --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/264

9 years, 3 months

← Newer
1
2
3
4
5
6
7
...
20
Older →

Jump to page:

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

sr-dev August 2015