sr-dev August 2022

sr-dev@lists.kamailio.org

16 participants
278 discussions

[kamailio/kamailio] siptrace: Trace in-dialog ACK (PR #3206)

by Stefan Mititelu

#### Pre-Submission Checklist    - [X] Commit message has the format required by CONTRIBUTING guide - [X] Commits are split per component (core, individual modules, libs, utils, ...) - [X] Each component has a single commit (if not, squash them into one commit) - [X] No commits to README files for modules (changes must be done to docbook files in `doc/` subfolder, the README file is autogenerated) #### Type Of Change - [X] Small bug fix (non-breaking change which fixes an issue) - [ ] New feature (non-breaking change which adds new functionality) - [ ] Breaking change (fix or feature that would change existing functionality) #### Checklist:  - [ ] PR should be backported to stable branches - [X] Tested changes locally - [ ] Related to issue #XXXX (replace XXXX with an open issue number) #### Description  While testing this module, I've seen it doesn't trace in-dialog ACKs. Found this old maillist discussion: https://lists.kamailio.org/pipermail/sr-users/2015-June/088731.html Added a condition to trace also the in-dialog ACKs. Is it ok to keep it as default or should I guard it via a modparam? Thanks, Stefan You can view, comment on, or merge this pull request online at: https://github.com/kamailio/kamailio/pull/3206 -- Commit Summary -- * siptrace: Trace in-dialog ACK -- File Changes -- M src/modules/siptrace/siptrace.c (7) -- Patch Links -- https://github.com/kamailio/kamailio/pull/3206.patch https://github.com/kamailio/kamailio/pull/3206.diff -- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/pull/3206 You are receiving this because you are subscribed to this thread. Message ID: <kamailio/kamailio/pull/3206(a)github.com>

1 year, 8 months

[kamailio/kamailio] Kamailio 5.6.0 crash when removing non-existing node from the dispatcher (Issue #3199)

by Denys Pozniak

### Description When deleting a node that does not resolve through the DNS and that does not exist in the dispatcher table, a Kamailio crash occurs. #### Reproduction 1. Create set id with one existing node in dispatcher table (DB MySQL) (for example sip:10.1.0.6 in set 200) 2. Reload dispatcher table: `kamcmd dispatcher.reload` 3. Delete a non-existent and non-resolvable node via kamcmd: `kamcmd dispatcher.remove 200 sip:127.0.0.1.test` ERROR: read reply failed: Success (0) 5. Kamailio crashed #### Debugging Data bt full info locals list ``` GNU gdb (GDB) Red Hat Enterprise Linux 7.6.1-120.el7 Copyright (C) 2013 Free Software Foundation, Inc. License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html> This is free software: you are free to change and redistribute it. There is NO WARRANTY, to the extent permitted by law. Type "show copying" and "show warranty" for details. This GDB was configured as "x86_64-redhat-linux-gnu". For bug reporting instructions, please see: <http://www.gnu.org/software/gdb/bugs/>... Reading symbols from /usr/sbin/kamailio...Reading symbols from /usr/lib/debug/usr/sbin/kamailio.debug...done. done. [New LWP 28559] [Thread debugging using libthread_db enabled] Using host libthread_db library "/lib64/libthread_db.so.1". Core was generated by `/usr/sbin/kamailio -DD -P /run/kamailio/kamailio.pid -f /etc/kamailio/kamailio.'. Program terminated with signal 11, Segmentation fault. #0 0x00007fd7f6705b0c in ds_filter_dest_cb (node=0x7fd7d6213270, i=0, arg=0x7ffe0e9c8a60) at dispatch.c:2660 2660 if(node->id == filter_arg->setid && node->dlist[i].uri.len == filter_arg->dest->uri.len && Missing separate debuginfos, use: debuginfo-install bzip2-libs-1.0.6-13.el7.x86_64 elfutils-libelf-0.176-5.el7.x86_64 elfutils-libs-0.176-5.el7.x86_64 glibc-2.17-326.el7_9.x86_64 keyutils-libs-1.5.8-3.el7.x86_64 krb5-libs-1.15.1-54.el7_9.x86_64 libattr-2.4.46-13.el7.x86_64 libcap-2.22-11.el7.x86_64 libcom_err-1.42.9-19.el7.x86_64 libgcc-4.8.5-44.el7.x86_64 libselinux-2.5-15.el7.x86_64 libstdc++-4.8.5-44.el7.x86_64 mariadb-libs-5.5.68-1.el7.x86_64 openssl-libs-1.0.2k-25.el7_9.x86_64 pcre-8.32-17.el7.x86_64 systemd-libs-219-78.el7_9.5.x86_64 xz-libs-5.2.2-2.el7_9.x86_64 zlib-1.2.7-20.el7_9.x86_64 (gdb) bt full #0 0x00007fd7f6705b0c in ds_filter_dest_cb (node=0x7fd7d6213270, i=0, arg=0x7ffe0e9c8a60) at dispatch.c:2660 filter_arg = 0x7ffe0e9c8a60 __FUNCTION__ = "ds_filter_dest_cb" #1 0x00007fd7f66dc077 in ds_iter_set (node=0x7fd7d6213270, ds_action_cb=0x7fd7f6705a8b <ds_filter_dest_cb>, ds_action_arg=0x7ffe0e9c8a60) at dispatch.c:213 i = 0 #2 0x00007fd7f66dc050 in ds_iter_set (node=0x7fd7d62144d0, ds_action_cb=0x7fd7f6705a8b <ds_filter_dest_cb>, ds_action_arg=0x7ffe0e9c8a60) at dispatch.c:210 i = 1 #3 0x00007fd7f66dc050 in ds_iter_set (node=0x7fd7d6211e08, ds_action_cb=0x7fd7f6705a8b <ds_filter_dest_cb>, ds_action_arg=0x7ffe0e9c8a60) at dispatch.c:210 i = 1 #4 0x00007fd7f66dc050 in ds_iter_set (node=0x7fd7d620e6c0, ds_action_cb=0x7fd7f6705a8b <ds_filter_dest_cb>, ds_action_arg=0x7ffe0e9c8a60) at dispatch.c:210 i = 1 #5 0x00007fd7f66dc050 in ds_iter_set (node=0x7fd7d620bf90, ds_action_cb=0x7fd7f6705a8b <ds_filter_dest_cb>, ds_action_arg=0x7ffe0e9c8a60) at dispatch.c:210 i = 0 #6 0x00007fd7f6706252 in ds_remove_dst (group=200, address=0x7ffe0e9c8b40) at dispatch.c:2691 setn = 7 filter_arg = {setid = 200, dest = 0x0, setn = 0x7ffe0e9c8a80} dp = 0x0 __FUNCTION__ = "ds_remove_dst" #7 0x00007fd7f66d72d1 in dispatcher_rpc_remove (rpc=0x7fd7f6d93620 <binrpc_callbacks>, ctx=0x7ffe0e9c8c40) at dispatcher.c:1982 group = 200 dest = {s = 0x208dcc3 "sip:127.0.0.1.test", len = 18} __FUNCTION__ = "dispatcher_rpc_remove" #8 0x00007fd7f6b640dc in process_rpc_req (buf=0x208dca4 "\241\003+\030\234\243\221\221\022dispatcher.remove", size=50, bytes_needed=0x7ffe0e9c8e8c, sh=0x7ffe0e9c8df0, saved_state=0x209dca8) at binrpc_run.c:688 err = 0 val = {name = {s = 0x100c8 <Address 0x100c8 out of bounds>, len = 4312272}, type = 1, u = {strval = {s = 0x208dcad "dispatcher.remove", len = 17}, fval = 1.6865036056773515e-316, intval = 34135213, end = 34135213}} rpc_e = 0x7fd7fb8c3af0 f_ctx = {in = {ctx = {tlen = 43, cookie = 412918673, type = 0, flags = 1, offset = 43, in_struct = 0, in_array = 0}, s = 0x208dcd6 "", end = 0x208dcd6 "", record_no = 2, in_struct = 0}, out = {pkt = {body = 0x209dd40 "", end = 0x20a5d40 "", crt = 0x209dd40 ""}, structs = { next = 0x7ffe0e9c8c90, prev = 0x7ffe0e9c8c90}}, send_h = 0x7ffe0e9c8df0, method = 0x208dcad "dispatcher.remove", gc = 0x0, replied = 0, err_code = 0, err_phrase = {s = 0x0, len = 0}} ctx = 0x7ffe0e9c8c40 __FUNCTION__ = "process_rpc_req" #9 0x00007fd7f6b5910e in handle_stream_read (s_c=0x208dc70, idx=-1) at io_listener.c:512 bytes_free = 65535 bytes_read = 50 bytes_needed = 32727 bytes_processed = 1 r = 0x208dc90 sh = {fd = 10, type = 0, from = {sa_in = {s = {sa_family = 28688, sa_data = "x\373\327\177\000\000_zm\000\000\000\000"}, sin = {sin_family = 28688, sin_port = 64376, sin_addr = {s_addr = 32727}, sin_zero = "_zm\000\000\000\000"}, sin6 = {sin6_family = 28688, sin6_port = 64376, sin6_flowinfo = 32727, sin6_addr = {__in6_u = {__u6_addr8 = "_zm\000\000\000\000\000\020px\373\002\000\000", __u6_addr16 = {31327, 109, 0, 0, 28688, 64376, 2, 0}, __u6_addr32 = {7174751, 0, 4218974224, 2}}}, sin6_scope_id = 245104641}, sas = {ss_family = 28688, __ss_padding = "x\373\327\177\000\000_zm\000\000\000\000\000\020px\373\002\000\000\000\001\000\234\016\376\177\000\000\235\354\234\370\327\177\000\000@\364{\373\327\177", '\000' <repeats 12 times>, "\200\000\000\000\000\000\200\317k\000\000\000\000\000\200\245\r\000\000\000\000\000\200\060\024\000\000\000\000\000\001\000\000\000\000\000\000\000\250\a\373\325\327\177\000\000\004\000\000\000\000\000\000\000X\243\214\373\000\000\000", __ss_align = 4540108464}}, sa_un = {sun_family = 28688, sun_path = "x\373\327\177\000\000_zm\000\000\000\000\000\020px\373\002\000\000\000\001\000\234\016\376\177\000\000\235\354\234\370\327\177\000\000@\364{\373\327\177", '\000' <repeats 12 times>, "\200\000\000\000\000\000\200\317k\000\000\000\000\000\200\245\r\000\000\000\000\000\200\060\024\000\000\000\000\000\001\000\000\000\000\000\000\000\250\a\373\325\327\177\000\000\004\000\000\000\000"}}, from_len = 0} __FUNCTION__ = "handle_stream_read" #10 0x00007fd7f6b5aebe in handle_io (fm=0x7fd7fb8c8db0, events=1, idx=-1) at io_listener.c:708 ret = 1 __FUNCTION__ = "handle_io" #11 0x00007fd7f6b51ca2 in io_wait_loop_epoll (h=0x7fd7f6d83340 <ctl_io_h>, t=10, repeat=0) at ../../core/io_wait.h:1070 n = 1 r = 0 fm = 0x7fd7fb8c8db0 revents = 1 __FUNCTION__ = "io_wait_loop_epoll" #12 0x00007fd7f6b55dfb in io_listen_loop (fd_no=1, cs_lst=0x2068ac0) at io_listener.c:281 max_fd_no = 239 poll_err = 0x0 poll_method = 2 cs = 0x0 type = 2 __FUNCTION__ = "io_listen_loop" #13 0x00007fd7f6b7453b in mod_child (rank=0) at ctl.c:338 pid = 0 cs = 0x80000000000000ee rpc_handler = 1 __FUNCTION__ = "mod_child" #14 0x00000000005d0d80 in init_mod_child (m=0x7fd7fb82b600, rank=0) at core/sr_module.c:877 ret = 0 __FUNCTION__ = "init_mod_child" #15 0x00000000005d09d0 in init_mod_child (m=0x7fd7fb82bc38, rank=0) at core/sr_module.c:870 ret = 0 __FUNCTION__ = "init_mod_child" #16 0x00000000005d09d0 in init_mod_child (m=0x7fd7fb82d710, rank=0) at core/sr_module.c:870 ret = 0 __FUNCTION__ = "init_mod_child" #17 0x00000000005d09d0 in init_mod_child (m=0x7fd7fb82e178, rank=0) at core/sr_module.c:870 ret = 0 __FUNCTION__ = "init_mod_child" #18 0x00000000005d09d0 in init_mod_child (m=0x7fd7fb82e7e8, rank=0) at core/sr_module.c:870 ret = 0 ---Type <return> to continue, or q <return> to quit--- __FUNCTION__ = "init_mod_child" #19 0x00000000005d09d0 in init_mod_child (m=0x7fd7fb82f5f8, rank=0) at core/sr_module.c:870 ret = 0 __FUNCTION__ = "init_mod_child" #20 0x00000000005d09d0 in init_mod_child (m=0x7fd7fb833730, rank=0) at core/sr_module.c:870 ret = 32 __FUNCTION__ = "init_mod_child" #21 0x00000000005d09d0 in init_mod_child (m=0x7fd7fb834b30, rank=0) at core/sr_module.c:870 ret = 0 __FUNCTION__ = "init_mod_child" #22 0x00000000005d1547 in init_child (rank=0) at core/sr_module.c:931 ret = -74709488 type = 0x835ed5 "PROC_MAIN" __FUNCTION__ = "init_child" #23 0x000000000042dc7d in main_loop () at main.c:1841 i = 8 pid = 28555 si = 0x0 si_desc = "udp receiver child=7 sock=10.1.131.126:5060\000\327\177\000\000\200\264\211\373\327\177\000\000P\247\203\373\327\177\000\000\000\235\234\016\376\177\000\000 \202\202\373\327\177\000\000\360\256\201\000\000\000\000\000\370\240\211\373\327\177\000\000c\276\201\000\000\000\000\000\373\257\201\000\000\000\000\000\004\000\000\000\000\000\000\000\000p\367\325\327\177\000" nrprocs = 8 woneinit = 1 __FUNCTION__ = "main_loop" #24 0x00000000004377eb in main (argc=10, argv=0x7ffe0e9ca358) at main.c:3074 cfg_stream = 0x1fb1670 c = -1 r = 0 tmp = 0x7ffe0e9cbf07 "" tmp_len = 1472 port = 960 proto = 32727 ahost = 0x0 aport = 0 options = 0x7f3c80 ":f:cm:M:dVIhEeb:l:L:n:vKrRDTN:W:w:t:u:g:P:G:SQ:O:a:A:x:X:Y:" ret = -1 seed = 751594692 rfd = 4 debug_save = 0 debug_flag = 0 dont_fork_cnt = 2 n_lst = 0x7ffe0e9ca210 p = 0xf0b5ff <Address 0xf0b5ff out of bounds> st = {st_dev = 20, st_ino = 14179, st_nlink = 2, st_mode = 16832, st_uid = 996, st_gid = 992, __pad0 = 0, st_rdev = 0, st_size = 40, st_blksize = 4096, st_blocks = 0, st_atim = {tv_sec = 1645116507, tv_nsec = 547546400}, st_mtim = {tv_sec = 1658752241, tv_nsec = 765745118}, st_ctim = {tv_sec = 1658752241, tv_nsec = 765745118}, __unused = {0, 0, 0}} tbuf = '\000' <repeats 392 times>... option_index = 0 long_options = {{name = 0x7f60af "help", has_arg = 0, flag = 0x0, val = 104}, {name = 0x7f0f54 "version", has_arg = 0, flag = 0x0, val = 118}, {name = 0x7f60b4 "alias", has_arg = 1, flag = 0x0, val = 1024}, {name = 0x7f60ba "subst", has_arg = 1, flag = 0x0, val = 1025}, { name = 0x7f60c0 "substdef", has_arg = 1, flag = 0x0, val = 1026}, {name = 0x7f60c9 "substdefs", has_arg = 1, flag = 0x0, val = 1027}, {name = 0x7f60d3 "server-id", has_arg = 1, flag = 0x0, val = 1028}, {name = 0x7f60dd "loadmodule", has_arg = 1, flag = 0x0, val = 1029}, { name = 0x7f60e8 "modparam", has_arg = 1, flag = 0x0, val = 1030}, {name = 0x7f60f1 "log-engine", has_arg = 1, flag = 0x0, val = 1031}, {name = 0x7f60fc "debug", has_arg = 1, flag = 0x0, val = 1032}, {name = 0x7f6102 "cfg-print", has_arg = 0, flag = 0x0, val = 1033}, { name = 0x7f610c "atexit", has_arg = 1, flag = 0x0, val = 1034}, {name = 0x0, has_arg = 0, flag = 0x0, val = 0}} __FUNCTION__ = "main" (gdb) info locals filter_arg = 0x7ffe0e9c8a60 __FUNCTION__ = "ds_filter_dest_cb" (gdb) list 2655 /* callback for removing nodes based on setid & address */ 2656 void ds_filter_dest_cb(ds_set_t *node, int i, void *arg) 2657 { 2658 struct ds_filter_dest_cb_arg *filter_arg = (typeof(filter_arg)) arg; 2659 2660 if(node->id == filter_arg->setid && node->dlist[i].uri.len == filter_arg->dest->uri.len && 2661 strncmp(node->dlist[i].uri.s, filter_arg->dest->uri.s, filter_arg->dest->uri.len) == 0) 2662 return; 2663 2664 if(add_dest2list(node->id, node->dlist[i].uri, node->dlist[i].flags, ``` #### Log Messages ``` Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: ctl [../../core/io_wait.h:375]: io_watch_add(): processing io_watch_add(0x7f9032d07340, 10, 3, 0x1cdac70) - fd_no=1 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: ctl [io_listener.c:442]: handle_new_connect(): handle_stream read: new connection (1) on /var/run/kamailio//kamailio_ctl Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: ctl [io_listener.c:498]: handle_stream_read(): bytes read: 50 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/resolve.c:1784]: str2ipbuf(): error - too many dots in [127.0.0.1.test] Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (127.0.0.1.test(14), 1), h=312 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/resolve.c:1784]: str2ipbuf(): error - too many dots in [127.0.0.1.test] Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/resolve.c:784]: get_record(): lookup(127.0.0.1.test, 1) failed Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:768]: dns_cache_mk_bad_entry(): (127.0.0.1.test, 1, 5, 1) Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:709]: dns_cache_add(): adding 127.0.0.1.test(14) 1 (flags=1) at 312 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: ERROR: dispatcher [dispatch.c:517]: pack_dest(): could not resolve 127.0.0.1.test (missing no-probing flag?!?) Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (gate.core.test.local(41), 1), h=977 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [2/1] <sip:gate.core.test.local:5080> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-626a9082134bb82ce844e99a-1.asterisk-626a9082134bb82ce844e99a.core.test.local(104), 1), h=127 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [121/1] <sip:asterisk-626a9082134bb82ce844e99a-1.asterisk-626a9082134bb82ce844e99a.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-626a9082134bb82ce844e99a-0.asterisk-626a9082134bb82ce844e99a.core.test.local(104), 1), h=126 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [121/2] <sip:asterisk-626a9082134bb82ce844e99a-0.asterisk-626a9082134bb82ce844e99a.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [16/1] <sip:10.1.144.125:1100> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-623478edd337ae4eb75e4592-1.asterisk-623478edd337ae4eb75e4592.core.test.local(104), 1), h=395 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [134/1] <sip:asterisk-623478edd337ae4eb75e4592-1.asterisk-623478edd337ae4eb75e4592.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-623478edd337ae4eb75e4592-0.asterisk-623478edd337ae4eb75e4592.core.test.local(104), 1), h=394 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [134/2] <sip:asterisk-623478edd337ae4eb75e4592-0.asterisk-623478edd337ae4eb75e4592.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-62cbe764f1967d2acee73137-1.asterisk-62cbe764f1967d2acee73137.core.test.local(104), 1), h=950 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [142/1] <sip:asterisk-62cbe764f1967d2acee73137-1.asterisk-62cbe764f1967d2acee73137.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-62cbe764f1967d2acee73137-0.asterisk-62cbe764f1967d2acee73137.core.test.local(104), 1), h=949 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [142/2] <sip:asterisk-62cbe764f1967d2acee73137-0.asterisk-62cbe764f1967d2acee73137.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-62863d882418f0548acb8878-1.asterisk-62863d882418f0548acb8878.core.test.local(104), 1), h=291 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [135/1] <sip:asterisk-62863d882418f0548acb8878-1.asterisk-62863d882418f0548acb8878.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-62863d882418f0548acb8878-0.asterisk-62863d882418f0548acb8878.core.test.local(104), 1), h=290 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [135/2] <sip:asterisk-62863d882418f0548acb8878-0.asterisk-62863d882418f0548acb8878.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-62de8c25cb42803beeb73dbe-1.asterisk-62de8c25cb42803beeb73dbe.core.test.local(104), 1), h=771 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [144/1] <sip:asterisk-62de8c25cb42803beeb73dbe-1.asterisk-62de8c25cb42803beeb73dbe.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: <core> [core/dns_cache.c:534]: _dns_hash_find(): (asterisk-62de8c25cb42803beeb73dbe-0.asterisk-62de8c25cb42803beeb73dbe.core.test.local(104), 1), h=770 Jul 25 12:55:04 core /usr/sbin/kamailio[28880]: DEBUG: dispatcher [dispatch.c:581]: add_dest2list(): dest [144/2] <sip:asterisk-62de8c25cb42803beeb73dbe-0.asterisk-62de8c25cb42803beeb73dbe.core.test.local:11000> Jul 25 12:55:04 core /usr/sbin/kamailio[28892]: CRITICAL: <core> [core/pass_fd.c:277]: receive_fd(): EOF on 5 Jul 25 12:55:04 core /usr/sbin/kamailio[28892]: DEBUG: <core> [core/tcp_main.c:3853]: handle_ser_child(): dead child 14, pid 28880 (shutting down?) Jul 25 12:55:04 core /usr/sbin/kamailio[28892]: DEBUG: <core> [core/io_wait.h:600]: io_watch_del(): DBG: io_watch_del (0xafde80, 5, -1, 0x0) fd_no=37 called Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: ALERT: <core> [main.c:775]: handle_sigs(): child process 28880 exited by a signal 11 Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: ALERT: <core> [main.c:778]: handle_sigs(): core was generated Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: INFO: <core> [main.c:799]: handle_sigs(): terminating due to SIGCHLD Jul 25 12:55:04 core /usr/sbin/kamailio[28892]: INFO: <core> [main.c:854]: sig_usr(): signal 15 received Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: <core> [core/sr_module.c:832]: destroy_modules(): starting modules destroy phase Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: xhttp_prom [xhttp_prom.c:256]: mod_destroy(): cleaning up Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: xhttp_prom [prom_metric.c:243]: prom_metric_close(): Freeing lock Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [t_funcs.c:84]: tm_shutdown(): start Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [t_funcs.c:87]: tm_shutdown(): emptying hash table Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [h_table.c:132]: free_cell_helper(): freeing transaction 0x7f90121d6b60 from h_table.c:466 Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [h_table.c:132]: free_cell_helper(): freeing transaction 0x7f9012201120 from h_table.c:466 Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [h_table.c:132]: free_cell_helper(): freeing transaction 0x7f90122034b0 from h_table.c:466 Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [h_table.c:132]: free_cell_helper(): freeing transaction 0x7f90121fca20 from h_table.c:466 Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [h_table.c:132]: free_cell_helper(): freeing transaction 0x7f90121fed90 from h_table.c:466 Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [t_funcs.c:89]: tm_shutdown(): removing semaphores Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [t_funcs.c:91]: tm_shutdown(): destroying tmcb lists Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: tm [t_funcs.c:94]: tm_shutdown(): done Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: INFO: <core> [core/sctp_core.c:53]: sctp_core_destroy(): SCTP API not initialized Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: <core> [core/mem/shm.c:290]: shm_destroy_manager(): destroying memory manager: q_malloc Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: <core> [core/mem/q_malloc.c:1260]: qm_shm_lock_destroy(): destroying the shared memory lock Jul 25 12:55:04 core /usr/sbin/kamailio[28820]: DEBUG: <core> [core/mem/pkg.c:99]: pkg_destroy_manager(): destroying memory manager: q_malloc Jul 25 12:55:04 core systemd[1]: kamailio.service: main process exited, code=exited, status=1/FAILURE Jul 25 12:55:04 core systemd[1]: Unit kamailio.service entered failed state. Jul 25 12:55:04 core systemd[1]: kamailio.service failed. Jul 25 12:55:05 core systemd[1]: kamailio.service holdoff time over, scheduling restart. Jul 25 12:55:05 core systemd[1]: Stopped Kamailio - the Open Source SIP Server. ``` ### Additional Information * **Kamailio Version** - output of `kamailio -v` ``` version: kamailio 5.6.0 (x86_64/linux) b0d6a6 flags: USE_TCP, USE_TLS, USE_SCTP, TLS_HOOKS, USE_RAW_SOCKS, DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MMAP, PKG_MALLOC, Q_MALLOC, F_MALLOC, TLSF_MALLOC, DBG_SR_MEMORY, USE_FUTEX, FAST_LOCK-ADAPTIVE_WAIT, USE_DNS_CACHE, USE_DNS_FAILOVER, USE_NAPTR, USE_DST_BLOCKLIST, HAVE_RESOLV_RES ADAPTIVE_WAIT_LOOPS 1024, MAX_RECV_BUFFER_SIZE 262144, MAX_URI_SIZE 1024, BUF_SIZE 65535, DEFAULT PKG_SIZE 8MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, select. id: b0d6a6 compiled on 13:24:32 May 30 2022 with gcc 4.8.5 ``` * **Operating System**:  ``` CentOS Linux release 7.9.2009 (Core) ``` -- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/3199 You are receiving this because you are subscribed to this thread. Message ID: <kamailio/kamailio/issues/3199(a)github.com>

1 year, 8 months

git:master:13a40219: dispatcher: check if packing address fails on removing via rpc

by Daniel-Constantin Mierla

Module: kamailio Branch: master Commit: 13a4021914057ca66eaa91e2ea022e1827963b70 URL: https://github.com/kamailio/kamailio/commit/13a4021914057ca66eaa91e2ea022e1… Author: Daniel-Constantin Mierla <miconda(a)gmail.com> Committer: Daniel-Constantin Mierla <miconda(a)gmail.com> Date: 2022-08-04T11:50:50+02:00 dispatcher: check if packing address fails on removing via rpc - GH #3199 --- Modified: src/modules/dispatcher/dispatch.c --- Diff: https://github.com/kamailio/kamailio/commit/13a4021914057ca66eaa91e2ea022e1… Patch: https://github.com/kamailio/kamailio/commit/13a4021914057ca66eaa91e2ea022e1… --- diff --git a/src/modules/dispatcher/dispatch.c b/src/modules/dispatcher/dispatch.c index d19098800a..7d818538d6 100644 --- a/src/modules/dispatcher/dispatch.c +++ b/src/modules/dispatcher/dispatch.c @@ -2680,6 +2680,11 @@ int ds_remove_dst(int group, str *address) setn = 0; dp = pack_dest(*address, 0, 0, NULL, 0); + if(dp==NULL) { + LM_ERR("failed to pack address: %d %.*s\n", group, + address->len, address->s); + return -1; + } filter_arg.setid = group; filter_arg.dest = dp; filter_arg.setn = &setn;

1 year, 8 months

git:5.6:1d03d9e8: usrloc: fix sending keepalive messages for IPv6, it was missing the brackets

by Henning Westerholt

Module: kamailio Branch: 5.6 Commit: 1d03d9e8312e6e9b494f6d243fe9b2b4ea890eaf URL: https://github.com/kamailio/kamailio/commit/1d03d9e8312e6e9b494f6d243fe9b2b… Author: Henning Westerholt <hw(a)gilawa.com> Committer: Henning Westerholt <hw(a)gilawa.com> Date: 2022-08-02T08:52:48Z usrloc: fix sending keepalive messages for IPv6, it was missing the brackets (cherry picked from commit bd40f0d08f5d0f14b301cb492d1082c71be91c50) --- Modified: src/modules/usrloc/ul_keepalive.c --- Diff: https://github.com/kamailio/kamailio/commit/1d03d9e8312e6e9b494f6d243fe9b2b… Patch: https://github.com/kamailio/kamailio/commit/1d03d9e8312e6e9b494f6d243fe9b2b… --- diff --git a/src/modules/usrloc/ul_keepalive.c b/src/modules/usrloc/ul_keepalive.c index a69a76d8b9..5b1572483b 100644 --- a/src/modules/usrloc/ul_keepalive.c +++ b/src/modules/usrloc/ul_keepalive.c @@ -61,7 +61,7 @@ Content-Length: 0\r\n\r\n" #define ULKA_CALLID_PREFIX_LEN (sizeof(ULKA_CALLID_PREFIX) - 1) #define ULKA_MSG "%.*s %.*s SIP/2.0\r\n" \ - "Via: SIP/2.0/%.*s %.*s:%.*s;branch=z9hG4bKx.%u.%u.0\r\n" \ + "Via: SIP/2.0/%.*s %s%.*s%s:%.*s;branch=z9hG4bKx.%u.%u.0\r\n" \ "%s%.*s%.*s" \ "From: <%.*s>;tag=%.*s-%x-%lx-%lx-%x.%x\r\n" \ "To: <sip:%.*s%s%.*s>\r\n" \ @@ -101,6 +101,7 @@ int ul_ka_urecord(urecord_t *ur) socket_info_t *ssock; dest_info_t idst; unsigned int bcnt = 0; + unsigned int via_ipv6 = 0; int aortype = 0; int i; struct timeval tv; @@ -195,8 +196,14 @@ int ul_ka_urecord(urecord_t *ur) idst.send_sock = ssock; if(ssock->useinfo.name.len > 0) { + if (ssock->useinfo.address.af == AF_INET6) { + via_ipv6 = 1; + } vaddr = ssock->useinfo.name; } else { + if (ssock->address.af == AF_INET6) { + via_ipv6 = 1; + } vaddr = ssock->address_str; } if(ssock->useinfo.port_no > 0) { @@ -212,7 +219,9 @@ int ul_ka_urecord(urecord_t *ur) ul_ka_method.len, ul_ka_method.s, uc->c.len, uc->c.s, sproto.len, sproto.s, + (via_ipv6==1)?"[":"", vaddr.len, vaddr.s, + (via_ipv6==1)?"]":"", vport.len, vport.s, _ul_ka_counter, bcnt, (uc->path.len>0)?"Route: ":"",

1 year, 9 months

git:5.4:14670263: usrloc: fix sending keepalive messages for IPv6, it was missing the brackets

by Henning Westerholt

Module: kamailio Branch: 5.4 Commit: 14670263837dd158f8f4512a474ea7dee3a561b3 URL: https://github.com/kamailio/kamailio/commit/14670263837dd158f8f4512a474ea7d… Author: Henning Westerholt <hw(a)gilawa.com> Committer: Henning Westerholt <hw(a)gilawa.com> Date: 2022-08-02T08:50:30Z usrloc: fix sending keepalive messages for IPv6, it was missing the brackets (cherry picked from commit bd40f0d08f5d0f14b301cb492d1082c71be91c50) --- Modified: src/modules/usrloc/ul_keepalive.c --- Diff: https://github.com/kamailio/kamailio/commit/14670263837dd158f8f4512a474ea7d… Patch: https://github.com/kamailio/kamailio/commit/14670263837dd158f8f4512a474ea7d… --- diff --git a/src/modules/usrloc/ul_keepalive.c b/src/modules/usrloc/ul_keepalive.c index a69a76d8b9..5b1572483b 100644 --- a/src/modules/usrloc/ul_keepalive.c +++ b/src/modules/usrloc/ul_keepalive.c @@ -61,7 +61,7 @@ Content-Length: 0\r\n\r\n" #define ULKA_CALLID_PREFIX_LEN (sizeof(ULKA_CALLID_PREFIX) - 1) #define ULKA_MSG "%.*s %.*s SIP/2.0\r\n" \ - "Via: SIP/2.0/%.*s %.*s:%.*s;branch=z9hG4bKx.%u.%u.0\r\n" \ + "Via: SIP/2.0/%.*s %s%.*s%s:%.*s;branch=z9hG4bKx.%u.%u.0\r\n" \ "%s%.*s%.*s" \ "From: <%.*s>;tag=%.*s-%x-%lx-%lx-%x.%x\r\n" \ "To: <sip:%.*s%s%.*s>\r\n" \ @@ -101,6 +101,7 @@ int ul_ka_urecord(urecord_t *ur) socket_info_t *ssock; dest_info_t idst; unsigned int bcnt = 0; + unsigned int via_ipv6 = 0; int aortype = 0; int i; struct timeval tv; @@ -195,8 +196,14 @@ int ul_ka_urecord(urecord_t *ur) idst.send_sock = ssock; if(ssock->useinfo.name.len > 0) { + if (ssock->useinfo.address.af == AF_INET6) { + via_ipv6 = 1; + } vaddr = ssock->useinfo.name; } else { + if (ssock->address.af == AF_INET6) { + via_ipv6 = 1; + } vaddr = ssock->address_str; } if(ssock->useinfo.port_no > 0) { @@ -212,7 +219,9 @@ int ul_ka_urecord(urecord_t *ur) ul_ka_method.len, ul_ka_method.s, uc->c.len, uc->c.s, sproto.len, sproto.s, + (via_ipv6==1)?"[":"", vaddr.len, vaddr.s, + (via_ipv6==1)?"]":"", vport.len, vport.s, _ul_ka_counter, bcnt, (uc->path.len>0)?"Route: ":"",

1 year, 9 months

git:5.5:4bc1b874: usrloc: fix sending keepalive messages for IPv6, it was missing the brackets

by Henning Westerholt

Module: kamailio Branch: 5.5 Commit: 4bc1b874330f81e582cd8aa109ee62af437d6674 URL: https://github.com/kamailio/kamailio/commit/4bc1b874330f81e582cd8aa109ee62a… Author: Henning Westerholt <hw(a)gilawa.com> Committer: Henning Westerholt <hw(a)gilawa.com> Date: 2022-08-02T08:49:00Z usrloc: fix sending keepalive messages for IPv6, it was missing the brackets (cherry picked from commit bd40f0d08f5d0f14b301cb492d1082c71be91c50) --- Modified: src/modules/usrloc/ul_keepalive.c --- Diff: https://github.com/kamailio/kamailio/commit/4bc1b874330f81e582cd8aa109ee62a… Patch: https://github.com/kamailio/kamailio/commit/4bc1b874330f81e582cd8aa109ee62a… --- diff --git a/src/modules/usrloc/ul_keepalive.c b/src/modules/usrloc/ul_keepalive.c index a69a76d8b9..5b1572483b 100644 --- a/src/modules/usrloc/ul_keepalive.c +++ b/src/modules/usrloc/ul_keepalive.c @@ -61,7 +61,7 @@ Content-Length: 0\r\n\r\n" #define ULKA_CALLID_PREFIX_LEN (sizeof(ULKA_CALLID_PREFIX) - 1) #define ULKA_MSG "%.*s %.*s SIP/2.0\r\n" \ - "Via: SIP/2.0/%.*s %.*s:%.*s;branch=z9hG4bKx.%u.%u.0\r\n" \ + "Via: SIP/2.0/%.*s %s%.*s%s:%.*s;branch=z9hG4bKx.%u.%u.0\r\n" \ "%s%.*s%.*s" \ "From: <%.*s>;tag=%.*s-%x-%lx-%lx-%x.%x\r\n" \ "To: <sip:%.*s%s%.*s>\r\n" \ @@ -101,6 +101,7 @@ int ul_ka_urecord(urecord_t *ur) socket_info_t *ssock; dest_info_t idst; unsigned int bcnt = 0; + unsigned int via_ipv6 = 0; int aortype = 0; int i; struct timeval tv; @@ -195,8 +196,14 @@ int ul_ka_urecord(urecord_t *ur) idst.send_sock = ssock; if(ssock->useinfo.name.len > 0) { + if (ssock->useinfo.address.af == AF_INET6) { + via_ipv6 = 1; + } vaddr = ssock->useinfo.name; } else { + if (ssock->address.af == AF_INET6) { + via_ipv6 = 1; + } vaddr = ssock->address_str; } if(ssock->useinfo.port_no > 0) { @@ -212,7 +219,9 @@ int ul_ka_urecord(urecord_t *ur) ul_ka_method.len, ul_ka_method.s, uc->c.len, uc->c.s, sproto.len, sproto.s, + (via_ipv6==1)?"[":"", vaddr.len, vaddr.s, + (via_ipv6==1)?"]":"", vport.len, vport.s, _ul_ka_counter, bcnt, (uc->path.len>0)?"Route: ":"",

1 year, 9 months

git:master:bd40f0d0: usrloc: fix sending keepalive messages for IPv6, it was missing the brackets

by Henning Westerholt

Module: kamailio Branch: master Commit: bd40f0d08f5d0f14b301cb492d1082c71be91c50 URL: https://github.com/kamailio/kamailio/commit/bd40f0d08f5d0f14b301cb492d1082c… Author: Henning Westerholt <hw(a)gilawa.com> Committer: Henning Westerholt <hw(a)gilawa.com> Date: 2022-08-02T08:47:52Z usrloc: fix sending keepalive messages for IPv6, it was missing the brackets --- Modified: src/modules/usrloc/ul_keepalive.c --- Diff: https://github.com/kamailio/kamailio/commit/bd40f0d08f5d0f14b301cb492d1082c… Patch: https://github.com/kamailio/kamailio/commit/bd40f0d08f5d0f14b301cb492d1082c… --- diff --git a/src/modules/usrloc/ul_keepalive.c b/src/modules/usrloc/ul_keepalive.c index a69a76d8b9..5b1572483b 100644 --- a/src/modules/usrloc/ul_keepalive.c +++ b/src/modules/usrloc/ul_keepalive.c @@ -61,7 +61,7 @@ Content-Length: 0\r\n\r\n" #define ULKA_CALLID_PREFIX_LEN (sizeof(ULKA_CALLID_PREFIX) - 1) #define ULKA_MSG "%.*s %.*s SIP/2.0\r\n" \ - "Via: SIP/2.0/%.*s %.*s:%.*s;branch=z9hG4bKx.%u.%u.0\r\n" \ + "Via: SIP/2.0/%.*s %s%.*s%s:%.*s;branch=z9hG4bKx.%u.%u.0\r\n" \ "%s%.*s%.*s" \ "From: <%.*s>;tag=%.*s-%x-%lx-%lx-%x.%x\r\n" \ "To: <sip:%.*s%s%.*s>\r\n" \ @@ -101,6 +101,7 @@ int ul_ka_urecord(urecord_t *ur) socket_info_t *ssock; dest_info_t idst; unsigned int bcnt = 0; + unsigned int via_ipv6 = 0; int aortype = 0; int i; struct timeval tv; @@ -195,8 +196,14 @@ int ul_ka_urecord(urecord_t *ur) idst.send_sock = ssock; if(ssock->useinfo.name.len > 0) { + if (ssock->useinfo.address.af == AF_INET6) { + via_ipv6 = 1; + } vaddr = ssock->useinfo.name; } else { + if (ssock->address.af == AF_INET6) { + via_ipv6 = 1; + } vaddr = ssock->address_str; } if(ssock->useinfo.port_no > 0) { @@ -212,7 +219,9 @@ int ul_ka_urecord(urecord_t *ur) ul_ka_method.len, ul_ka_method.s, uc->c.len, uc->c.s, sproto.len, sproto.s, + (via_ipv6==1)?"[":"", vaddr.len, vaddr.s, + (via_ipv6==1)?"]":"", vport.len, vport.s, _ul_ka_counter, bcnt, (uc->path.len>0)?"Route: ":"",

1 year, 9 months

[kamailio/kamailio] unexpected error log when KSR.xhttp.xhttp_reply(404...) (Issue #3204)

by hance2010

### Description  some unexpected error in kamailio.log when KSR.xhttp.xhttp_reply(404...) ### Troubleshooting #### Reproduction cat /etc/kamailio/kamailio.cfg ``` listen=tcp:127.0.0.1:5060 tcp_accept_no_cl=yes debug=3 log_stderror=yes loadmodule "jsonrpcs.so" loadmodule "tm.so" loadmodule "sl.so" loadmodule "pv.so" loadmodule "xlog.so" loadmodule "ctl.so" loadmodule "cfg_rpc.so" loadmodule "xhttp.so" loadmodule "app_lua.so" modparam("xhttp", "event_callback", "ksr_xhttp_event") modparam("app_lua", "load", "/etc/kamailio/kamailio.lua") cfgengine "lua" ``` cat /etc/kamailio/kamailio.lua ``` function ksr_request_route() KSR.sl.send_reply(200, "OK") KSR.x.exit() end function ksr_xhttp_event() KSR.set_reply_no_connect() KSR.set_reply_close() KSR.xhttp.xhttp_reply(404, "Not Found", "", "") KSR.x.exit() end ``` curl http://127.0.0.1:5060 -v  #### Debugging Data  ``` (paste your debugging data here) ``` #### Log Messages  ``` 15(5845) DEBUG: <core> [core/ip_addr.c:571]: print_ip(): tcpconn_new: new tcp connection: 127.0.0.1 15(5845) DEBUG: <core> [core/tcp_main.c:1216]: tcpconn_new(): on port 54884, type 2, socket 29 15(5845) DEBUG: <core> [core/tcp_main.c:1548]: tcpconn_add(): hashes: 1846:1992:1067, 1 15(5845) DEBUG: <core> [core/io_wait.h:374]: io_watch_add(): processing io_watch_add(0x563c94544c20, 29, 2, 0x7fb2e4691088) - fd_no=23 15(5845) DEBUG: <core> [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del (0x563c94544c20, 29, -1, 0x0) fd_no=24 called 15(5845) DEBUG: <core> [core/tcp_main.c:4545]: handle_tcpconn_ev(): sending to child, events 1 15(5845) DEBUG: <core> [core/tcp_main.c:4215]: send2child(): selected tcp worker idx:0 proc:7 pid:5837 for activity on [tcp:127.0.0.1:5060], 0x7fb2e4691088 7(5837) DEBUG: <core> [core/tcp_read.c:1735]: handle_io(): received n=8 con=0x7fb2e4691088, fd=6 7(5837) DEBUG: <core> [core/parser/parse_fline.c:249]: parse_first_line(): first line type 1 (request) flags 2 7(5837) DEBUG: <core> [core/parser/parse_fline.c:249]: parse_first_line(): first line type 1 (request) flags 2 7(5837) DEBUG: <core> [core/parser/msg_parser.c:677]: parse_msg(): SIP Request: 7(5837) DEBUG: <core> [core/parser/msg_parser.c:678]: parse_msg(): method: <GET> 7(5837) DEBUG: <core> [core/parser/msg_parser.c:680]: parse_msg(): uri: </> 7(5837) DEBUG: <core> [core/parser/msg_parser.c:682]: parse_msg(): version: <HTTP/1.1> 7(5837) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [Host] type 0 7(5837) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [User-Agent] type 28 7(5837) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [Accept] type 23 7(5837) DEBUG: <core> [core/parser/msg_parser.c:91]: get_hdr_field(): found end of header 7(5837) DEBUG: <core> [core/receive.c:389]: receive_msg(): --- received sip message - request - call-id: [] - cseq: [] 7(5837) DEBUG: <core> [core/receive.c:261]: ksr_evrt_pre_routing(): event route core:pre-routing not defined 7(5837) DEBUG: <core> [core/receive.c:426]: receive_msg(): handling non-sip request message 7(5837) DEBUG: xhttp [xhttp_mod.c:371]: xhttp_handler(): new fake msg created (112 bytes): <GET / HTTP/1.1 Via: SIP/2.0/TCP 127.0.0.1:54884 Host: 127.0.0.1:5060 User-Agent: curl/7.74.0 Accept: */* > 7(5837) DEBUG: <core> [core/parser/parse_fline.c:249]: parse_first_line(): first line type 1 (request) flags 2 7(5837) DEBUG: <core> [core/parser/msg_parser.c:677]: parse_msg(): SIP Request: 7(5837) DEBUG: <core> [core/parser/msg_parser.c:678]: parse_msg(): method: <GET> 7(5837) DEBUG: <core> [core/parser/msg_parser.c:680]: parse_msg(): uri: </> 7(5837) DEBUG: <core> [core/parser/msg_parser.c:682]: parse_msg(): version: <HTTP/1.1> 7(5837) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [Via] type 1 7(5837) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 7(5837) DEBUG: <core> [core/parser/msg_parser.c:555]: parse_headers(): Via found, flags=2 7(5837) DEBUG: <core> [core/parser/msg_parser.c:557]: parse_headers(): this is the first via 7(5837) DEBUG: app_lua [app_lua_api.c:549]: sr_lua_reload_script(): No need to reload [/etc/kamailio/kamailio.lua] is version 0 7(5837) DEBUG: app_lua [app_lua_api.c:733]: app_lua_run_ex(): executing Lua function: [[ksr_xhttp_event]] 7(5837) DEBUG: app_lua [app_lua_api.c:735]: app_lua_run_ex(): lua top index is: 11 7(5837) DEBUG: app_lua [app_lua_api.c:1067]: sr_kemi_lua_exec_func_ex(): param[0] for: xhttp_reply is int: 404 7(5837) DEBUG: app_lua [app_lua_api.c:1063]: sr_kemi_lua_exec_func_ex(): param[1] for: xhttp_reply is str: Not Found 7(5837) DEBUG: app_lua [app_lua_api.c:1063]: sr_kemi_lua_exec_func_ex(): param[2] for: xhttp_reply is str: 7(5837) DEBUG: app_lua [app_lua_api.c:1063]: sr_kemi_lua_exec_func_ex(): param[3] for: xhttp_reply is str: 7(5837) DEBUG: xhttp [xhttp_mod.c:426]: xhttp_send_reply(): sending out response: 404 Not Found 7(5837) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [Host] type 0 7(5837) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [User-Agent] type 28 7(5837) DEBUG: <core> [core/parser/parse_hname2.c:301]: parse_sip_header_name(): parsed header name [Accept] type 23 7(5837) DEBUG: <core> [core/parser/msg_parser.c:91]: get_hdr_field(): found end of header 7(5837) DEBUG: <core> [core/msg_translator.c:162]: check_via_address(): (127.0.0.1, 127.0.0.1, 0) 7(5837) DEBUG: <core> [core/tcp_main.c:1644]: _tcpconn_find(): found connection by id: 1 7(5837) DEBUG: <core> [core/tcp_main.c:2526]: tcpconn_send_put(): send from reader (5837 (7)), reusing fd 7(5837) DEBUG: <core> [core/tcp_main.c:2761]: tcpconn_do_send(): sending... 7(5837) DEBUG: <core> [core/tcp_main.c:2794]: tcpconn_do_send(): after real write: c= 0x7fb2e4691088 n=120 fd=6 7(5837) DEBUG: <core> [core/tcp_main.c:2795]: tcpconn_do_send(): buf= HTTP/1.1 404 Not Found Sia: SIP/2.0/TCP 127.0.0.1:54884 Server: kamailio (5.6.1 (x86_64/linux)) Content-Length: 0 15(5845) DEBUG: <core> [core/tcp_main.c:3880]: handle_ser_child(): read response= 7fb2e4691088, -1, fd -1 from 7 (5837) 7(5837) DEBUG: <core> [core/parser/parse_fline.c:255]: parse_first_line(): bad request first line 7(5837) DEBUG: <core> [core/parser/parse_fline.c:258]: parse_first_line(): at line 0 char 16: 7(5837) DEBUG: <core> [core/parser/parse_fline.c:264]: parse_first_line(): parsed so far: HTTP/1.1 404 Not 7(5837) ERROR: <core> [core/parser/parse_fline.c:271]: parse_first_line(): parse_first_line: bad message (offset: 16) 7(5837) DEBUG: <core> [core/parser/msg_parser.c:673]: parse_msg(): invalid message 7(5837) ERROR: <core> [core/parser/msg_parser.c:747]: parse_msg(): ERROR: parse_msg: message=<HTTP/1.1 404 Not Found Sia: SIP/2.0/TCP 127.0.0.1:54884 Server: kamailio (5.6.1 (x86_64/linux)) Content-Length: 0 > 7(5837) ERROR: <core> [core/msg_translator.c:3256]: build_sip_msg_from_buf(): parsing failed 7(5837) DEBUG: app_lua [app_lua_mod.c:164]: sr_kemi_config_engine_lua(): execution of route type 513 with name [ksr_xhttp_event] returned 1 7(5837) DEBUG: <core> [core/receive.c:432]: receive_msg(): finished handling non-sip request message 7(5837) DEBUG: <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil) 7(5837) DEBUG: <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil) 7(5837) DEBUG: <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil) 7(5837) DEBUG: <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil) 7(5837) DEBUG: <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil) 7(5837) DEBUG: <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil) 7(5837) DEBUG: <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil) 7(5837) DEBUG: <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil) 7(5837) DEBUG: <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil) 7(5837) DEBUG: <core> [core/receive.c:628]: receive_msg(): cleaning up 7(5837) DEBUG: <core> [core/io_wait.h:374]: io_watch_add(): processing io_watch_add(0x563c94588ec0, 6, 2, 0x7fb2e4691088) - fd_no=1 7(5837) DEBUG: <core> [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del (0x563c94588ec0, 6, -1, 0x10) fd_no=2 called 7(5837) DEBUG: <core> [core/tcp_read.c:1870]: handle_io(): removing from list 0x7fb2e4691088 id 1 fd 6, state -1, flags 4028, main fd -1, refcnt 1 ([127.0.0.1]:54884 -> [127.0.0.1]:5060) 7(5837) DEBUG: <core> [core/tcp_read.c:1653]: release_tcpconn(): releasing con 0x7fb2e4691088, state -2, fd=6, id=1 ([127.0.0.1]:54884 -> [127.0.0.1]:5060) 7(5837) DEBUG: <core> [core/tcp_read.c:1657]: release_tcpconn(): extra_data (nil) 15(5845) DEBUG: <core> [core/tcp_main.c:3641]: handle_tcp_child(): reader response= 7fb2e4691088, -2 from 0 15(5845) DEBUG: <core> [core/tcp_main.c:3566]: tcp_emit_closed_event(): TCP closed event creation triggered (reason: 0) 15(5845) DEBUG: <core> [core/tcp_main.c:3574]: tcp_emit_closed_event(): no callback registering for handling TCP closed event 2(5832) DEBUG: <core> [core/timer.c:422]: adjust_ticks(): adjusting timer ticks (677813966) with 312 ms (5 ticks) 2(5832) DEBUG: <core> [core/timer.c:422]: adjust_ticks(): adjusting timer ticks (677815246) with 250 ms (4 ticks) 2(5832) DEBUG: <core> [core/timer.c:422]: adjust_ticks(): adjusting timer ticks (677816206) with 250 ms (4 ticks) 2(5832) DEBUG: <core> [core/timer.c:422]: adjust_ticks(): adjusting timer ticks (677817406) with 312 ms (5 ticks) ``` #### SIP Traffic  ``` (paste your sip traffic here) ``` ### Possible Solutions  ### Additional Information * **Kamailio Version** - output of `kamailio -v` ``` Kamailio5.6.1 ``` * **Operating System**:  ``` Debian11 ``` -- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/3204 You are receiving this because you are subscribed to this thread. Message ID: <kamailio/kamailio/issues/3204(a)github.com>

1 year, 9 months

Jump to page:

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

sr-dev August 2022