sr-dev March 2016

sr-dev@lists.kamailio.org

27 participants
387 discussions

[kamailio] Presence module should support a 32bit unsigned expire value (#521)

by foucse

Hello, ``` kamailio -v version: kamailio 4.4.0-pre0 (i386/linux) f39d14-dirty flags: STATS: Off, EXTRA_DEBUG, USE_TCP, USE_TLS, USE_SCTP, TLS_HOOKS, USE_RAW_SOCKS, DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MEM, 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_BLACKLIST, HAVE_RESOLV_RES ADAPTIVE_WAIT_LOOPS=1024, MAX_RECV_BUFFER_SIZE 262144, MAX_LISTEN 16, MAX_URI_SIZE 1024, BUF_SIZE 65535, DEFAULT PKG_SIZE 8MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, select. id: f39d14 -dirty compiled on 17:01:22 Feb 12 2016 with gcc 4.9.3 ``` If I read well the RFC 3261 section 20.19 [https://tools.ietf.org/html/rfc3261#section-20.19] a SUBSCRIBE or a PUBLISH may have an Expires value of 2**32-1 = (4294967295). But it appears to me that this value is not supported in Kamailio even if I allow it as a max_expires option in configuration (modparam("presence", "max_expires", 4294967295)). Kamailio answers with the following: ``` 2016/02/29 09:02:59.343750 192.168.100.1:5060 -> 192.168.2.2:5062 SIP/2.0 423 Interval Too Brief via: SIP/2.0/UDP 192.168.2.2:5062;branch=z9hG4bK-11535-1-9;rport=5062 From: "User1" <sip:user1@example.com>;tag=11535-1 To: "User1" <sip:user1@example.com>;tag=dfba6eee28f067076993264b7e466d4e.151a Call-ID: 1-11545(a)192.168.1.1-user1 CSeq: 3 PUBLISH Expires: -2147483648 SIP-ETag: a.1456732971.11285.1.0 Server: kamailio (4.4.0-pre0 (i386/linux)) Content-Length: 0 ``` Which is not compliant with the RFC, the Expires value should not be signed. --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/521

8 years, 2 months

[kamailio] kamailio crash on receive_fd (version: 4.2 , >4.2.6) (#466)

by BroWater

Hello, Situation: Use a client (MCU) to call other clients through kamailio and hang up these calls, repeatedly Suddenly some clients were found fail to register, crash on kamailio was confirmed after checking the following log: Jan 7 03:42:24 /kamailio[1425]: CRITICAL: <core> [pass_fdc:293]: receive_fd(): EOF on 11 Jan 7 03:42:24 /kamailio[1415]: ALERT: <core> [mainc:784]: handle_sigs(): child process 1416 exited by a signal 6 Jan 7 03:42:24 /kamailio[1415]: ALERT: <core> [mainc:787]: handle_sigs(): core was generated And here is the `bt full` output from the coredump file: --------------------------------------------------------------------------------------------------------- #0 0x401cd8e8 in raise () from /lib/libcso6 No symbol table info available #1 0x401d171c in abort () from /lib/libcso6 No symbol table info available #2 0x401c6bf0 in __assert_fail_base () from /lib/libcso6 No symbol table info available #3 0x401c6cb8 in __assert_fail () from /lib/libcso6 No symbol table info available #4 0x400cb0a8 in pthread_mutex_lock () from /lib/libpthreadso0 No symbol table info available #5 0x40cdccdc in add_uac (t=0x419eb780, request=0x40408d18, uri=0x40408e88, next_hop=0x40408e90, path=0x40409094, proxy=0x0, fsocket=0x0, snd_flags=, proto=0, flags=0, instance=0x4040909c, ruid=0x404090a8, location_ua=0x404090b0) at t_fwdc:863 ret = 0 branch = 0 __FUNCTION__ = "add_uac" #6 0x40ce5598 in t_forward_nonack (t=0x419eb780, p_msg=0x40408d18, proxy=0x0, proto=0) at t_fwdc:1723 branch_ret = 0 lowest_ret = -1 current_uri = {s = 0x0, len = 0} added_branches = 0 first_branch = 0 i = 1 q = 0 t_invite = 0xffffffff success_branch = 1 try_new = 1 lock_replies = -1093035388 dst_uri = {s = 0x376a88 "<core>: dsetc", len = 1093296396} path = {s = 0x0, len = 0} instance = {s = 0xbed99a5c "\200\325\314@\034\342>@\204\342-@x\021\002", len = 1087391576} ruid = {s = 0x0, len = 1073991680} location_ua = {s = 0x21178 "", len = 0} si = 0x0 backup_bflags = 0 bflags = 0 __FUNCTION__ = "t_forward_nonack" #7 0x40ccd580 in t_relay_to (p_msg=0x40408d18, proxy=0x0, proto=0, replicate=0) at t_funcsc:355 ret = 0 new_tran = 1 t = 0x419eb780 dst = {send_sock = 0x40408d18, to = {s = {sa_family = 40716, sa_data = "\331\276\030\215@@\004\301\003A8\342>@"}, sin = {sin_family = 40716, sin_port = 48857, sin_addr = {s_addr = 1077972248}, sin_zero = "\004\301\003A8\342>@"}, sin6 = {sin6_family = 40716, sin6_port = 48857, sin6_flowinfo = 1077972248, sin6_addr = { __in6_u = {__u6_addr8 = "\004\301\003A8\342>@\000\000\000\000\fa*A", __u6_addr16 = {49412, 16643, 57912, 16446, 0, 0, 24844, 16682}, __u6_addr32 = { 1090765060, 1077862968, 0, 1093296396}}}, sin6_scope_id = 0}}, id = 135544, proto = 0 '\000', send_flags = {f = 0 '\000', blst_imask = 0 '\000'}} port = 48857 host = {s = 0x4031c534 "\001", len = 1077972248} comp = -1 __FUNCTION__ = "t_relay_to" #8 0x40d1cb98 in _w_t_relay_to (p_msg=0x40408d18, proxy=0x0, force_proto=0) at tmc:1517 t = 0x41086c80 res = -1093035156 __FUNCTION__ = "_w_t_relay_to" #9 0x40d1e1f4 in w_t_relay (p_msg=0x40408d18, _foo=0x0, _bar=0x0) at tmc:1718 No locals #10 0x00029aa0 in do_action (h=0xbed99fc8, a=0x40320f74, msg=0x40408d18) at actionc:1088 ret = -5 v = -1093034460 dst = {send_sock = 0x4036ee58, to = {s = {sa_family = 36120, sa_data = "@@\001\000\000\000`OK\000\324\235\331\276"}, sin = {sin_family = 36120, sin_port = 16448, sin_addr = {s_addr = 1}, sin_zero = "`OK\000\324\235\331\276"}, sin6 = {sin6_family = 36120, sin6_port = 16448, sin6_flowinfo = 1, sin6_addr = {__in6_u = { __u6_addr8 = "`OK\000\324\235\331\276\000\000\000\000\000\000\000", __u6_addr16 = {20320, 75, 40404, 48857, 0, 0, 0, 0}, __u6_addr32 = {4935520, 3201932756, 0, 0}}}, sin6_scope_id = 3201932896}}, id = 1077341784, proto = 24 '\030', send_flags = {f = 141 '\215', blst_imask = 64 '@'}} tmp = 0x4003d000 "H\377\002" new_uri = 0x0 end = 0xd72a4 "" crt = 0x0 cmd = 0x402ff898 len = 0 user = 135544 uri = {user = {s = 0x0, len = 0}, passwd = {s = 0x0, len = 0}, host = {s = 0x0, len = 1610612752}, port = {s = 0x8a <Address 0x8a out of bounds>, len = 2623776}, params = {s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = -1093034636}, sip_params = {s = 0x98f6c " ", len = 1077000232}, headers = {s = 0xbed99e78 "", len = 1077972248}, port_no = 46240, proto = 48857, type = 3727636, flags = 8388608, transport = {s = 0x6e9c68 <Address 0x6e9c68 out of bounds>, len = 967680}, ttl = {s = 0xbed99d7c "\001", len = 22}, user_param = {s = 0x4b4f4c "", len = 0}, maddr = {s = 0xbed99d5c "", len = 19}, method = {s = 0xbed99d84 "", len = 303571655}, lr = {s = 0x0, len = 135544}, r2 = {s = 0x0, len = 0}, gr = {s = 0xbed99d8c "\b\230\021", len = 1152864}, transport_val = { s = 0xbed99db0 "\001", len = 22}, ttl_val = {s = 0x4b4f4c "", len = 1}, user_param_val = {s = 0x280920 "\b0\033\345\004 \223\345\f0\033\345", len = 1}, maddr_val = {s = 0x4b4f60 "2", len = 0}, method_val = {s = 0xbed99d9c "$\341\021", len = 1153032}, lr_val = {s = 0x0, len = 1}, r2_val = {s = 0xbed99dbc "", len = 1171748}, gr_val = {s = 0x1 <Address 0x1 out of bounds>, len = -1093034400}} next_hop = {user = {s = 0xbed99c3c "", len = 1077972248}, passwd = {s = 0x4032aaac "0\003", len = -1093034880}, host = {s = 0x40408d18 "l\006", len = 1076849808}, port = {s = 0x3 <Address 0x3 out of bounds>, len = 0}, params = {s = 0x40317840 "", len = 1077972248}, sip_params = { s = 0xffffffff <Address 0xffffffff out of bounds>, len = 1076983872}, headers = {s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = 0}, port_no = 4472, proto = 2, type = ERROR_URI_T, flags = 0, transport = {s = 0x4003d000 "H\377\002", len = -1093033948}, ttl = {s = 0x29b18 "", len = 0}, user_param = { s = 0x40408d18 "l\006", len = 1077062316}, maddr = {s = 0xbed9b4a0 "\004", len = 0}, method = {s = 0x0, len = -1}, lr = { s = 0xbed99cac "l\234\331\276\370\233\331\276hi\003", len = 303571655}, r2 = {s = 0x0, len = 135544}, gr = {s = 0x40408d18 "l\006", len = 1077018000}, transport_val = {s = 0xbed9a0b0 "", len = 1073991680}, ttl_val = {s = 0xbed99c6c "\030\233\002", len = -1093035016}, user_param_val = {s = 0x36968 "", 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}} u = 0x0 port = 4632 dst_host = 0x4032abdc i = 4104020 flags = -1093034948 avp = 0x40310d88 st = {flags = 0, id = 0, name = {n = 1610612752, s = {s = 0x60000010 <Address 0x60000010 out of bounds>, len = 567153825}, re = 0x60000010}, avp = 0x280920} sct = 0x128264 sjt = 0x0 rve = 0x4 mct = 0x40408d18 rv = 0x4101b35c rv1 = 0x40408d18 c1 = {cache_type = RV_CACHE_EMPTY, val_type = 303571655, c = {avp_val = {n = 0, s = {s = 0x0, len = 135544}, re = 0x0}, pval = {rs = {s = 0x0, len = 135544}, ri = 0, flags = 0}}, i2s = "\000\320\003@l\234\331\276\350l\003\000\003\000\000\000\377\377\377\377l\234"} s = {s = 0x36968 "", len = 0} srevp = {0xbed99b84, 0xbed99b10} 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 = 2, string = 0x2 <Address 0x2 out of bounds>, str = {s = 0x2 <Address 0x2 out of bounds>, len = 0}, data = 0x2, attr = 0x2, select = 0x2}}, { type = RVE_FREE_FIXUP_ST, u = {number = 0, string = 0x0, str = {s = 0x0, len = 3}, data = 0x0, attr = 0x0, select = 0x0}}, {type = MODFIXUP_ST, u = { number = 1077333124, string = 0x4036cc84 "X\314\066@ ", str = {s = 0x4036cc84 "X\314\066@ ", len = 3}, data = 0x4036cc84, attr = 0x4036cc84, select = 0x4036cc84}}, {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" #11 0x00036ce8 in run_actions (h=0xbed99fc8, a=0x40320f74, msg=0x40408d18) at actionc:1583 t = 0x40320f74 ret = -1 mod = 0x0 ms = 0 __FUNCTION__ = "run_actions" #12 0x00037478 in run_actions_safe (h=0xbed9b4a0, a=0x40320f74, msg=0x40408d18) at actionc:1648 ctx = {rec_lev = 1, run_flags = 0, last_retcode = 0, jmp_env = {{__jmpbuf = {303571655, 0, 135544, 0, 0, 0, 1073991680, -1093034060, -1093034176, 223592, 0 <repeats 16 times>, 1610612752, 1100845520, 1416, 1100429564, 1164518012, 1077972248, 1077018000, -1093033808, 0, 1073991680, 1, 0, 0, 1077972248, -1, 0, 303571655, 303571655, 0, 135544, 0, 0, -1093033388, 226424, 0, 1077972248, 1077018000, -1093028704, 0, 0, -1, 1164092908, 303571655, 0, 135544, 0, 0, 0}, __mask_was_saved = 0, __saved_mask = {__val = {3201933468, 3201933352, 223592, 0 <repeats 16 times>, 1610612752, 3, 2623776, 3201933652, 0, 5, 3201933692, 1085865828, 7249368, 1077864140, 4849333, 4849164, 8}}}}} ret = -1 ign_on_break = 0 #13 0x0017ed68 in rval_get_int (h=0xbed9b4a0, msg=0x40408d18, i=0xbed9a390, rv=0x40321184, cache=0x0) at rvaluec:924 r_avp = 0x40408d18 avp_val = {n = 0, s = {s = 0x0, len = 4848947}, re = 0x0} pval = {rs = {s = 0x3dc238 "parse_headers", len = 4035544}, ri = 1077864140, flags = 1076748296} tmp = {s = 0x0, len = 5} s = 0x0 r = 0 ret = 0 destroy_pval = 0 __FUNCTION__ = "rval_get_int" #14 0x00183b3c in rval_expr_eval_int (h=0xbed9b4a0, msg=0x40408d18, res=0xbed9a390, rve=0x40321180) at rvaluec:1918 i1 = 5060 i2 = 1077972248 ret = -1 c1 = {cache_type = 135544, val_type = 1077018528, c = {avp_val = {n = -1093032424, s = {s = 0xbed9a618 "", len = 1077972248}, re = 0xbed9a618}, pval = {rs = { s = 0xbed9a618 "", len = 1077972248}, ri = -1093028704, flags = -1093033244}}, i2s = "\340r\346@\344\242\331\276$\211\344@\001\000\000\000\000\000\000\224"} c2 = {cache_type = 137, val_type = 4294967295, c = {avp_val = {n = 0, s = {s = 0x0, len = -1093033244}, re = 0x0}, pval = {rs = {s = 0x0, len = -1093033244}, ri = 1568104, flags = 192}}, i2s = "\000\000\000\000\b\000\000\000\220\221\235A\000\000\000\000\364@\347@\000"} rv1 = 0x0 rv2 = 0x402de008 __FUNCTION__ = "rval_expr_eval_int" #15 0x00183f00 in rval_expr_eval_int (h=0xbed9b4a0, msg=0x40408d18, res=0xbed9a618, rve=0x40321524) at rvaluec:1926 i1 = 0 i2 = -1093028704 ret = -1 c1 = {cache_type = RV_CACHE_EMPTY, val_type = RV_NONE, c = {avp_val = {n = 0, s = {s = 0x0, len = 0}, re = 0x0}, pval = {rs = {s = 0x0, len = 0}, ri = 0, flags = 0}}, i2s = "\000\000\000\000\000F0@\000\000\000\000\000\000\000\000\030\215@@\324\260"} c2 = {cache_type = 12, val_type = 4848849, c = {avp_val = {n = 4, s = {s = 0x4 <Address 0x4 out of bounds>, len = 303571655}, re = 0x4}, pval = {rs = { s = 0x4 <Address 0x4 out of bounds>, len = 303571655}, ri = 0, flags = 135544}}, i2s = "\000\000\000\000\000\000\000\000\000\320\003@\314\243\331\276\350l\003\000\000"} rv1 = 0x49fcc4 rv2 = 0x0 __FUNCTION__ = "rval_expr_eval_int" #16 0x00029600 in do_action (h=0xbed9b4a0, a=0x403218c8, msg=0x40408d18) at actionc:1064 ret = -5 v = 0 dst = {send_sock = 0x0, to = {s = {sa_family = 33508, sa_data = ">\000\240\325=\000\001\000\000\000\004\000\000"}, sin = {sin_family = 33508, sin_port = 62, sin_addr = {s_addr = 4052384}, sin_zero = "\001\000\000\000\004\000\000"}, sin6 = {sin6_family = 33508, sin6_port = 62, sin6_flowinfo = 4052384, sin6_addr = { __in6_u = {__u6_addr8 = "\001\000\000\000\004\000\000\000\300\247\331\276\000\000\000", __u6_addr16 = {1, 0, 4, 0, 42944, 48857, 0, 0}, __u6_addr32 = {1, 4, 3201935296, 0}}}, sin6_scope_id = 303571655}}, id = 0, proto = 199 '\307', send_flags = {f = 34 '"', blst_imask = 24 '\030'}} tmp = 0x0 new_uri = 0x3 <Address 0x3 out of bounds> end = 0xbed9a714 "<;\030" crt = 0x0 cmd = 0x40329d38 len = 0 user = 135544 uri = {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 = 1610612752}, headers = {s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = -1093032500}, port_no = 36716, proto = 9, type = 7, flags = 5, transport = {s = 0x40312380 "\001", len = -1093032232}, ttl = {s = 0x40408d18 "l\006", len = -1093028704}, user_param = {s = 0xbed9a59c "h\355\027", len = 1077572256}, maddr = {s = 0x0, len = 5}, method = {s = 0x1 <Address 0x1 out of bounds>, len = 0}, lr = {s = 0xbed9a62c "0?\030", len = 1568104}, r2 = { s = 0x6e9894 <Address 0x6e9894 out of bounds>, len = 968660}, gr = {s = 0x6 <Address 0x6 out of bounds>, len = 0}, transport_val = { s = 0x3dc7b4 "<core>: parser/parse_addr_specc", len = 303571655}, ttl_val = {s = 0x0, len = 135544}, user_param_val = {s = 0x0, len = 0}, maddr_val = { s = 0xbed9a63c "\214M)", len = 2604464}, method_val = {s = 0x3dd5a0 "parse_to_param", len = 285}, lr_val = {s = 0x40304600 "l?\002A\020\070\001A\001", len = 0}, r2_val = {s = 0x0, len = 0}, gr_val = {s = 0x0, len = 5}} next_hop = {user = {s = 0x0, len = 0}, passwd = {s = 0x0, len = 0}, host = {s = 0x0, len = 0}, port = {s = 0x0, len = 1077972248}, params = {s = 0x40329c04 "%\003", len = -1093032736}, sip_params = {s = 0x0, len = 0}, headers = {s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = 0}, port_no = 0, proto = 0, type = ERROR_URI_T, flags = 4294967295, transport = {s = 0x0, len = -1093032756}, ttl = {s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = 0}, user_param = { s = 0x21178 "", len = 0}, maddr = {s = 0x0, len = -1093032316}, method = {s = 0x37478 "\f", len = 141}, lr = {s = 0x40408d18 "l\006", len = 1077058564}, r2 = { s = 0xbed9b4a0 "\004", len = 0}, gr = {s = 0x0, len = -1}, transport_val = {s = 0x403a72a0 "\002", len = 303571655}, ttl_val = {s = 0x0, len = 135544}, user_param_val = {s = 0x0, len = 0}, maddr_val = {s = 0x0, len = 1073991680}, method_val = {s = 0xbed9a4cc "xt\003", len = -1093032872}, lr_val = {s = 0x36968 "", len = 0}, r2_val = {s = 0x0, len = 0}, gr_val = {s = 0x0, len = 0}} u = 0x0 port = 4632 dst_host = 0xbed9a8e4 i = -1093028704 flags = 1088786032 avp = 0x40408d18 st = {flags = 3201934540, id = 27880, name = {n = 0, s = {s = 0x0, len = 1073991680}, re = 0x0}, avp = 0xbed9a4b4} sct = 0x0 sjt = 0x121822c7 rve = 0x40321524 mct = 0x403ef9e0 rv = 0x4003d000 rv1 = 0xbed9aa48 c1 = {cache_type = 3201935400, val_type = 331612160, c = {avp_val = {n = 1, s = {s = 0x1 <Address 0x1 out of bounds>, len = 0}, re = 0x1}, pval = {rs = { s = 0x1 <Address 0x1 out of bounds>, len = 0}, ri = 1077972248, flags = 4848957}}, i2s = "\001\000\000\000\307\"\030\022\000\000\000\000x\021\002\000\000\000\000\000\000"} s = {s = 0x40e47ce8 "", len = 0} srevp = {0x40e474f0, 0xbed9a464} 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 = 2, string = 0x2 <Address 0x2 out of bounds>, str = {s = 0x2 <Address 0x2 out of bounds>, len = 0}, data = 0x2, attr = 0x2, select = 0x2}}, { type = RVE_FREE_FIXUP_ST, u = {number = 0, string = 0x0, str = {s = 0x0, len = 3}, data = 0x0, attr = 0x0, select = 0x0}}, {type = MODFIXUP_ST, u = { number = 1077333124, string = 0x4036cc84 "X\314\066@ ", str = {s = 0x4036cc84 "X\314\066@ ", len = 3}, data = 0x4036cc84, attr = 0x4036cc84, select = 0x4036cc84}}, {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" #17 0x00036ce8 in run_actions (h=0xbed9b4a0, a=0x4031ea14, msg=0x40408d18) at actionc:1583 t = 0x403218c8 ret = 1 mod = 0x0 ms = 0 __FUNCTION__ = "run_actions" #18 0x000263ac in do_action (h=0xbed9b4a0, a=0x4032b32c, msg=0x40408d18) at actionc:712 ret = -5 v = 0 dst = {send_sock = 0x0, to = {s = {sa_family = 24, sa_data = "\000\000\002\375I\000\000\000\000\000\320\220\235A"}, sin = {sin_family = 24, sin_port = 0, sin_addr = { s_addr = 4848898}, sin_zero = "\000\000\000\000\320\220\235A"}, sin6 = {sin6_family = 24, sin6_port = 0, sin6_flowinfo = 4848898, sin6_addr = {__in6_u = { __u6_addr8 = "\000\000\000\000\320\220\235A\000\000\000\000\000\000\000", __u6_addr16 = {0, 0, 37072, 16797, 0, 0, 0, 0}, __u6_addr32 = {0, 1100845264, 0, 0}}}, sin6_scope_id = 0}}, id = 0, proto = 199 '\307', send_flags = {f = 34 '"', blst_imask = 24 '\030'}} tmp = 0xbed9abb4 "\350l\003" new_uri = 0x121822c7 <Address 0x121822c7 out of bounds> end = 0x403ebfec "\232\301>@\030" crt = 0x280920 "\b0\033\345\004 \223\345\f0\033\345" cmd = 0x40329698 len = 0 user = 1077034684 uri = {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 = 1610612752}, headers = {s = 0x0, len = 0}, port_no = 0, proto = 0, type = 18, flags = 0, transport = {s = 0x40326164 "\001", len = -1093031160}, ttl = {s = 0x40408d18 "l\006", len = -1093028704}, user_param = {s = 0xbed9a9cc "h\355\027", len = 1076748296}, maddr = { s = 0xbed9a9f4 "\360\253\331\276\030\215@@\240\264\331\276\004", len = 1085865828}, method = {s = 0x1 <Address 0x1 out of bounds>, len = 0}, lr = { s = 0xbed9aa5c "<;\030", len = 1568104}, r2 = {s = 0x6e9a90 <Address 0x6e9a90 out of bounds>, len = 968152}, gr = { s = 0x116570 "\004\060\240\343\202!\240\341\002 \201\340\003\060\202\340", len = 1215756}, transport_val = {s = 0x4 <Address 0x4 out of bounds>, len = 145}, ttl_val = {s = 0x0, len = 303571655}, user_param_val = {s = 0x40328d8c "\004", len = -1093030928}, maddr_val = {s = 0x40408d18 "l\006", len = -1093028704}, method_val = {s = 0x4 <Address 0x4 out of bounds>, len = 1091096887}, lr_val = {s = 0xb <Address 0xb out of bounds>, len = 1091096872}, r2_val = {s = 0x0, len = 0}, gr_val = {s = 0x0, len = 5}} next_hop = {user = {s = 0x0, len = 0}, passwd = {s = 0x60000010 <Address 0x60000010 out of bounds>, len = 56}, host = {s = 0x403636c0 "\n", len = 3}, port = { s = 0x2 <Address 0x2 out of bounds>, len = 1077972248}, params = {s = 0x40329600 "#\003", len = -1093031664}, sip_params = { s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = 1076733136}, headers = {s = 0x12 <Address 0x12 out of bounds>, len = 0}, port_no = 0, proto = 0, type = 1077972248, flags = URI_USER_NORMALIZE, transport = {s = 0x0, len = 303571655}, ttl = {s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = 0}, user_param = {s = 0x21178 "", len = 0}, maddr = {s = 0x0, len = -1093031244}, method = {s = 0x37478 "\f", len = 1077972248}, lr = {s = 0x40408d18 "l\006", len = 1077057024}, r2 = {s = 0xbed9b4a0 "\004", len = 0}, gr = {s = 0x0, len = 1}, transport_val = {s = 0x40408d18 "l\006", len = 303571655}, ttl_val = {s = 0x0, len = 135544}, user_param_val = {s = 0x0, len = 0}, maddr_val = {s = 0x0, len = 1073991680}, method_val = {s = 0xbed9a8fc "xt\003", len = -1093031800}, lr_val = { s = 0x36968 "", len = 0}, r2_val = {s = 0x0, len = 0}, gr_val = {s = 0x0, len = 0}} u = 0x403242fc port = 48857 dst_host = 0x40408d18 i = 2 flags = 0 avp = 0x40408d18 st = {flags = 3201935612, id = 27880, name = {n = 0, s = {s = 0x0, len = 0}, re = 0x0}, avp = 0x0} sct = 0x403636a8 sjt = 0x40408d18 rve = 0x40329d34 mct = 0x113 rv = 0x4003d000 rv1 = 0xbed9ae78 c1 = {cache_type = RV_CACHE_EMPTY, val_type = 1073991680, c = {avp_val = {n = -1093031916, s = {s = 0xbed9a814 "xt\003", len = -1093032032}, re = 0xbed9a814}, pval = { rs = {s = 0xbed9a814 "xt\003", len = -1093032032}, ri = 223592, flags = 0}}, i2s = "\000\000\000\000\307\"\030\022\000\000\000\000x\021\002\000\000\000\000\000\000"} s = {s = 0x0, len = 0} srevp = {0x0, 0x21178} 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 = 2, string = 0x2 <Address 0x2 out of bounds>, str = {s = 0x2 <Address 0x2 out of bounds>, len = 0}, data = 0x2, attr = 0x2, select = 0x2}}, { type = RVE_FREE_FIXUP_ST, u = {number = 0, string = 0x0, str = {s = 0x0, len = 3}, data = 0x0, attr = 0x0, select = 0x0}}, {type = MODFIXUP_ST, u = { number = 1077333124, string = 0x4036cc84 "X\314\066@ ", str = {s = 0x4036cc84 "X\314\066@ ", len = 3}, data = 0x4036cc84, attr = 0x4036cc84, select = 0x4036cc84}}, {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" #19 0x00036ce8 in run_actions (h=0xbed9b4a0, a=0x40329ad4, msg=0x40408d18) at actionc:1583 t = 0x4032b32c ret = 1 mod = 0x0 ms = 0 __FUNCTION__ = "run_actions" #20 0x00029a2c in do_action (h=0xbed9b4a0, a=0x4032b454, msg=0x40408d18) at actionc:1079 ret = 1 v = 1 dst = {send_sock = 0x0, to = {s = {sa_family = 64960, sa_data = " @\b \000\000pD!@\340\070U"}, sin = {sin_family = 64960, sin_port = 16416, sin_addr = { s_addr = 8200}, sin_zero = "pD!@\340\070U"}, sin6 = {sin6_family = 64960, sin6_port = 16416, sin6_flowinfo = 8200, sin6_addr = {__in6_u = { __u6_addr8 = "pD!@\340\070U\000\000\240-@\000\000\000", __u6_addr16 = {17520, 16417, 14560, 85, 40960, 16429, 0, 0}, __u6_addr32 = {1075922032, 5585120, 1076731904, 0}}}, sin6_scope_id = 1}}, id = 0, proto = 21 '\025', send_flags = {f = 0 '\000', blst_imask = 0 '\000'}} tmp = 0x4108cee0 "xlog" new_uri = 0x4109e174 "\214\240\001" end = 0x79 <Address 0x79 out of bounds> crt = 0x4 <Address 0x4 out of bounds> cmd = 0x403152a0 len = 135544 user = 0 uri = {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 = 1610612752}, headers = {s = 0x402da228 "\001", len = 134217728}, port_no = 0, proto = 13362, type = 1076597596, flags = 1076611795, transport = { s = 0x402da228 "\001", len = 5585624}, ttl = {s = 0x402da4d0 "", len = 132392}, user_param = {s = 0x555a60 "", len = 124320}, maddr = {s = 0x555a60 "", len = 1075903936}, method = {s = 0xbed9ae9f "A\373\037", len = 1076728908}, lr = {s = 0x1 <Address 0x1 out of bounds>, len = 1076731904}, r2 = { s = 0x1 <Address 0x1 out of bounds>, len = 0}, gr = {s = 0x3 <Address 0x3 out of bounds>, len = -1093030216}, transport_val = {s = 0x402b73cc "%H:%M:%S", len = 1076731904}, ttl_val = {s = 0x402d926c ",t+@", len = 5585496}, user_param_val = {s = 0x402da4d0 "", len = 8200}, maddr_val = { s = 0x80 <Address 0x80 out of bounds>, len = 8200}, method_val = {s = 0xbed9af60 "", len = 5593696}, lr_val = {s = 0x402da228 "\001", len = 5585112}, r2_val = { s = 0x402da4d0 "", len = 132904}, gr_val = {s = 0x553ad8 "failed\n", len = 132392}} next_hop = {user = { s = 0x419d4700 "4 CIF=1;QCIF=1\r\na=rtpmap:31 H261/90000\r\na=fmtp:31 CIF=2;QCIF=2\r\na=sendrecv\r\nm=application 30006 RTP/SAVP 100\r\na=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:iBuE6kKiNbn30g7hkU2/qRE0ROKMAJZDD/Zg48t0\r\na=encr", len = 0}, passwd = {s = 0x419d4a44 "\220\001", len = 1100826908}, host = {s = 0x0, len = 5}, port = { s = 0x419d4a9c "\001", len = 1077972248}, params = {s = 0x40315020 "\226\002", len = -1093030592}, sip_params = {s = 0x49feb5 "", len = 4849103}, headers = { s = 0x8 <Address 0x8 out of bounds>, len = 0}, port_no = 0, proto = 0, type = 1089616044, flags = 4294967295, transport = {s = 0x0, len = 0}, ttl = { s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = 0}, user_param = {s = 0x21178 "", len = 0}, maddr = {s = 0x0, len = -1093030172}, method = { s = 0x37478 "\f", len = 1100827356}, lr = {s = 0x40408d18 "l\006", len = 1076973600}, r2 = {s = 0xbed9b4a0 "\004", len = 0}, gr = {s = 0x0, len = -1}, transport_val = {s = 0x40231380 "\b0\235\345\003`\206\340\336\372\377\352\v", len = 303571655}, ttl_val = {s = 0x0, len = 135544}, user_param_val = {s = 0x0, len = 0}, maddr_val = {s = 0x0, len = 1073991680}, method_val = {s = 0xbed9ad2c "xt\003", len = -1093030728}, lr_val = {s = 0x36968 "", len = 0}, r2_val = { s = 0x0, len = 0}, gr_val = {s = 0x0, len = 0}} u = 0x4109e174 port = 48857 dst_host = 0x1 i = -1093028704 flags = -1 avp = 0x40408d18 st = {flags = 3201936684, id = 27880, name = {n = 152, s = {s = 0x98 <Address 0x98 out of bounds>, len = 1100827388}, re = 0x98}, avp = 0xffffffff} sct = 0x403ece20 sjt = 0x40408d18 rve = 0x40329694 mct = 0x402d944c rv = 0x4003d000 rv1 = 0xbed9b2a8 c1 = {cache_type = 4849103, val_type = 1452104383, c = {avp_val = {n = 0, s = {s = 0x0, len = 0}, re = 0x0}, pval = {rs = {s = 0x0, len = 0}, ri = 8388608, flags = 7249432}}, i2s = "\254\070\362@\307\"\030\022\000\000\000\000x\021\002\000\000\000\000\000\000"} s = {s = 0x403ff4fc "\033", len = 4849333} srevp = {0xbed9ac8c, 0xbed9ac8c} 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 = 2, string = 0x2 <Address 0x2 out of bounds>, str = {s = 0x2 <Address 0x2 out of bounds>, len = 0}, data = 0x2, attr = 0x2, select = 0x2}}, { type = RVE_FREE_FIXUP_ST, u = {number = 0, string = 0x0, str = {s = 0x0, len = 3}, data = 0x0, attr = 0x0, select = 0x0}}, {type = MODFIXUP_ST, u = { number = 1077333124, string = 0x4036cc84 "X\314\066@ ", str = {s = 0x4036cc84 "X\314\066@ ", len = 3}, data = 0x4036cc84, attr = 0x4036cc84, select = 0x4036cc84}}, {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" #21 0x00036ce8 in run_actions (h=0xbed9b4a0, a=0x403294d0, msg=0x40408d18) at actionc:1583 t = 0x4032b454 ret = 1 mod = 0x4003d000 ms = 0 __FUNCTION__ = "run_actions" #22 0x000263ac in do_action (h=0xbed9b4a0, a=0x403160fc, msg=0x40408d18) at actionc:712 ret = -5 v = 0 dst = {send_sock = 0x115f68, to = {s = {sa_family = 36108, sa_data = "\022\000\004\000\000\000\240\000\000\000\364\360\334@"}, sin = {sin_family = 36108, sin_port = 18, sin_addr = {s_addr = 4}, sin_zero = "\240\000\000\000\364\360\334@"}, sin6 = {sin6_family = 36108, sin6_port = 18, sin6_flowinfo = 4, sin6_addr = {__in6_u = {__u6_addr8 = "\240\000\000\000\364\360\334@\307\"\030\022\004\263\331\276", __u6_addr16 = {160, 0, 61684, 16604, 8903, 4632, 45828, 48857}, __u6_addr32 = {160, 1088221428, 303571655, 3201938180}}}, sin6_scope_id = 1077862732}}, id = 0, proto = 0 '\000', send_flags = {f = 208 '\320', blst_imask = 3 '\003'}} tmp = 0x40e43104 "\f\220\002" new_uri = 0x121822c7 <Address 0x121822c7 out of bounds> end = 0x49fcb8 "Route: <sip:101009336:5060;nat=yes;lr=on>\r\nFrom: <sip:1000@101009336:5060>;tag=875590146\r\nTo: <sip:9144@101009336:5060>;tag=748310794\r\nCall-ID: 54544907\r\nCSeq: 6 INFO\r\nContact: <sip:1000@101" crt = 0x21178 "" cmd = 0x40304fe0 len = 626540 user = -1093028780 uri = {user = { s = 0x49fc9f "branch=z9hG4bK897806495\r\nRoute: <sip:101009336:5060;nat=yes;lr=on>\r\nFrom: <sip:1000@101009336:5060>;tag=875590146\r\nTo: <sip:9144@101009336:5060>;tag=748310794\r\nCall-ID: 54544907\r\nCSeq: 6 INFO", len = 0}, passwd = {s = 0x0, len = -1093029204}, host = {s = 0x40d588cc "<0\033\345\002\001", len = 2042}, port = {s = 0x7d8 <Address 0x7d8 out of bounds>, len = 1077972248}, params = { s = 0x49fc8c "09336:5062;rport;branch=z9hG4bK897806495\r\nRoute: <sip:101009336:5060;nat=yes;lr=on>\r\nFrom: <sip:1000@101009336:5060>;tag=875590146\r\nTo: <sip:9144@101009336:5060>;tag=748310794\r\nCall-ID: 545", len = 4115756}, sip_params = {s = 0x3ebab8 "<core>: parser/parse_viac", len = 32}, headers = { s = 0x402de008 "\001", len = 0}, port_no = 12660, proto = 16799, type = 98365684, flags = 4848822, transport = {s = 0x403ee130 " ", len = -1093029288}, ttl = { s = 0x4 <Address 0x4 out of bounds>, len = 4848788}, user_param = { s = 0x49fc98 ";rport;branch=z9hG4bK897806495\r\nRoute: <sip:101009336:5060;nat=yes;lr=on>\r\nFrom: <sip:1000@101009336:5060>;tag=875590146\r\nTo: <sip:9144@101009336:5060>;tag=748310794\r\nCall-ID: 54544907\r\nCSeq:", len = 4848792}, maddr = {s = 0x4 <Address 0x4 out of bounds>, len = 331743235}, method = { s = 0xbed9b374 "$b(", len = -1093029004}, lr = {s = 0x403ee14c "\350", len = 1077933164}, r2 = {s = 0xbed9b2a4 "", len = 1100951944}, gr = {s = 0xbed9b4d8 "", len = 1}, transport_val = {s = 0xffffffff <Address 0xffffffff out of bounds>, len = -1}, ttl_val = {s = 0xbed9b2ac "\373\377\377\377\310\264\331\276\001", len = 1077933164}, user_param_val = {s = 0x49feb5 "", len = 4848763}, maddr_val = {s = 0x0, len = 84271112}, method_val = {s = 0xbed9b29c "L\341>@", len = 1085865828}, lr_val = {s = 0x419f31e0 "\310", len = 0}, r2_val = {s = 0x4 <Address 0x4 out of bounds>, len = 8388608}, gr_val = { s = 0x6ea098 <Address 0x6ea098 out of bounds>, len = 966608}} next_hop = {user = {s = 0x0, len = 135544}, passwd = {s = 0x280920 "\b0\033\345\004 \223\345\f0\033\345", len = -1093029596}, host = {s = 0xbed9b124 "", len = 1076748296}, port = {s = 0xbed9b14c "l\217\t", len = 1085865828}, params = {s = 0x0, len = 0}, sip_params = {s = 0x0, len = 8388608}, headers = { s = 0x6ea058 <Address 0x6ea058 out of bounds>, len = 966672}, port_no = 24488, proto = 17, type = 1215756, flags = 4, transport = { s = 0x9e <Address 0x9e out of bounds>, len = 0}, ttl = {s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = -1093029452}, user_param = {s = 0x98f6c " ", len = 109}, maddr = {s = 0x40addfec "", len = -1093029428}, method = {s = 0x27d028 "\004", len = 4}, lr = {s = 0x3e9fa4 "core", len = 0}, r2 = { s = 0x3 <Address 0x3 out of bounds>, len = -1093029340}, gr = {s = 0x2e14d0 "", len = 0}, transport_val = {s = 0x0, len = -1093029428}, ttl_val = { s = 0xbed9b1cc "\314\210\325@\372\a", len = 1088115668}, user_param_val = {s = 0x40db0528 "tm: t_replyc", len = 8}, maddr_val = {s = 0x0, len = 4111032}, method_val = {s = 0x121822c7 <Address 0x121822c7 out of bounds>, len = 0}, lr_val = {s = 0x21178 "", len = 0}, r2_val = {s = 0x0, len = -1093029282}, gr_val = { s = 0xbed9b25f "\005\234\262\331\276d\377\270@\340\061\237A", len = 4849333}} u = 0x157 port = 61 dst_host = 0x8 i = 4 flags = 626540 avp = 0xbed9b44c st = {flags = 4, id = 141, name = {n = 343, s = {s = 0x157 <Address 0x157 out of bounds>, len = 1088221428}, re = 0x157}, avp = 0xbed9b15c} sct = 0x1d sjt = 0x40408d18 rve = 0x4031529c mct = 0x49fddf rv = 0x128d0c rv1 = 0x0 c1 = {cache_type = 3201937612, val_type = 3201937612, c = {avp_val = {n = 1076748296, s = {s = 0x402de008 "\001", len = -1093029644}, re = 0x402de008}, pval = {rs = { s = 0x402de008 "\001", len = -1093029644}, ri = 1085865828, flags = 86}}, i2s = "\000\000\000\000\254\177=\000\000\000\200\000\200\233n\000\350\304\016\000\200d"} s = {s = 0x1b5 <Address 0x1b5 out of bounds>, len = 2623776} srevp = {0x419f3210, 0x386f70} 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 = 2, string = 0x2 <Address 0x2 out of bounds>, str = {s = 0x2 <Address 0x2 out of bounds>, len = 0}, data = 0x2, attr = 0x2, select = 0x2}}, { type = RVE_FREE_FIXUP_ST, u = {number = 0, string = 0x0, str = {s = 0x0, len = 3}, data = 0x0, attr = 0x0, select = 0x0}}, {type = MODFIXUP_ST, u = { number = 1077333124, string = 0x4036cc84 "X\314\066@ ", str = {s = 0x4036cc84 "X\314\066@ ", len = 3}, data = 0x4036cc84, attr = 0x4036cc84, select = 0x4036cc84}}, {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" #23 0x00036ce8 in run_actions (h=0xbed9b4a0, a=0x4030fbc0, msg=0x40408d18) at actionc:1583 t = 0x403160fc ret = 1 mod = 0x0 ms = 0 __FUNCTION__ = "run_actions" #24 0x00037538 in run_top_route (a=0x4030fbc0, msg=0x40408d18, c=0x0) at actionc:1669 ctx = {rec_lev = 4, run_flags = 0, last_retcode = -1, jmp_env = {{__jmpbuf = {303571655, 0, 135544, 0, 0, 0, 1073991680, -1093028724, -1093028840, 223592, 0 <repeats 16 times>, 1610612752, 966492, 1138420, 1215756, 2623776, -1093028532, -1093028532, 1076748296, -1093028492, 1085865828, 1077859196, 0, 14, 8388608, 7250220, 966460, 1138388, 1215756, 4, 162, 0, 303571655, -1093028388, 626540, 4124784, 4122588, 3607920, 0, 1077859224, 303571655, 1077872804, 1076748296, 0, 5, 1164317944, 1164522740, -1093028324, 1077972248}, __mask_was_saved = 0, __saved_mask = {__val = {4848676, 1, 13, 3749280, 3201938908, 1, 13, 0, 1085944084, 0, 135544, 303571655, 0, 1073741825, 1077972248, 0, 303571655, 3201939020, 1691804, 0, 135544, 3751672, 4848758, 1077972268, 82, 1, 1077972248, 225, 1073741825, 1077972220, 0, 0}}}}} p = 0xbed9b4a0 ret = 135544 sfbk = 0 #25 0x0013ce80 in receive_msg ( buf=0x49fc24 "INFO sip:9144@101009144:5060;transport=TCP;alias=101009144:36841~2 SIP/20\r\nVia: SIP/20/UDP 101009336:5062;rport;branch=z9hG4bK897806495\r\nRoute: <sip:101009336:5060;nat=yes;lr=on>\r\nFrom: ", len=657, rcv_info=0xbed9b8b4) at receivec:216 msg = 0x40408d18 ctx = {rec_lev = 0, run_flags = 1, last_retcode = -1093028124, jmp_env = {{__jmpbuf = {-1093028084, 1094111756, 0, 1077352742, 2127, 5, 0, 1074750288, 1075464840, 1073835920, 1094553688, 1100326448, 76, 8403020, 1075499064, 1073991680, -1093027904, 1073913596, 1073993916, 1075465032, 0, 0, 1, 2127, 1074750344, 1074749440, 105499, 1075499832, 88616, 1, 19, 268896657, 1073994528, 0, -1093027928, 105499, 1074750344, -1093027836, -1093027860, 1073836664, -1093027928, 1073994436, 0, 1074750344, 1, 0, 1, 1073994088, 2623776, -12717463, 1073994528, 0, -1093027856, 104072, 1074750328, 0, 1073994528, 1073836664, 1073994088, 1073994436, 0, 1074750328, 1, 0}, __mask_was_saved = 1, __saved_mask = {__val = {1073994088, 3201939468, 626540, 1075499064, 1074749440, 0, 108658490, 1073994528, 0, 7714291, 135544, 1073994088, 0, 1074349248, 0, 1, 4427664, 0, 1073991680, 3201939580, 1073854768, 1074750344, 1, 1, 0, 0, 1075499064, 303571655, 0, 135544, 0, 0}}}}} ret = 2487808 inb = { s = 0x49fc24 "INFO sip:9144@101009144:5060;transport=TCP;alias=101009144:36841~2 SIP/20\r\nVia: SIP/20/UDP 101009336:5062;rport;branch=z9hG4bK897806495\r\nRoute: <sip:101009336:5060;nat=yes;lr=on>\r\nFrom: ", len = 657} __FUNCTION__ = "receive_msg" #26 0x00266708 in udp_rcv_loop () at udp_serverc:521 len = 657 buf = "INFO sip:9144@101009144:5060;transport=TCP;alias=101009144:36841~2 SIP/20\r\nVia: SIP/20/UDP 101009336:5062;rport;branch=z9hG4bK897806495\r\nRoute: <sip:101009336:5060;nat=yes;lr=on>\r\nFrom: " tmp = 0x0 from = 0x402fe830 fromlen = 16 ri = {src_ip = {af = 2, len = 4, u = {addrl = {610100234, 3201939668, 1935216, 1759989418}, addr32 = {610100234, 3201939668, 1935216, 1759989418}, addr16 = {25610, 9309, 47316, 48857, 34672, 29, 20138, 26855}, addr = "\nd]$\324\270\331\276p\207\035\000\252N\347h"}}, dst_ip = {af = 2, len = 4, u = {addrl = {610100234, 0, 0, 0}, addr32 = {610100234, 0, 0, 0}, addr16 = {25610, 9309, 0, 0, 0, 0, 0, 0}, addr = "\nd]$", '\000' <repeats 11 times>}}, src_port = 5062, dst_port = 5060, proto_reserved1 = 0, proto_reserved2 = 0, src_su = {s = {sa_family = 2, sa_data = "\023\306\nd]$\000\000\000\000\000\000\000"}, sin = {sin_family = 2, sin_port = 50707, sin_addr = {s_addr = 610100234}, sin_zero = "\000\000\000\000\000\000\000"}, sin6 = {sin6_family = 2, sin6_port = 50707, sin6_flowinfo = 610100234, 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 = 0x402fde78, proto = 1 '\001'} __FUNCTION__ = "udp_rcv_loop" #27 0x000c90a0 in main_loop () at mainc:1629 i = 0 pid = 0 si = 0x402fde78 si_desc = "udp receiver child=0 sock=101009336:5060 (101009336:5060)\000\300u1@h\t;\000 \236\060@\000\000\000\000x\021\002\000\024\260=@\000\000\000\000d\272\331\276\260\275'\000\230\331\070\000X\311\070\000\030\000\000\000\000\240[A\000\000\000\000\000\320\003@\244\303\230A" r = 1073991680 nrprocs = 1 __FUNCTION__ = "main_loop" #28 0x000d0830 in main (argc=5, argv=0xbed9bd04) at mainc:2581 cfg_stream = 0x4b5008 c = -1 r = 0 tmp = 0x4003dac4 "8c\017@\a" tmp_len = -1093026968 port = 1073836664 proto = -1093026900 options = 0x383880 ":f:cm:M:dVIhEeb:l:L:n:vKrRDTN:W:w:t:u:g:P:G:SQ:O:a:A:" ret = -1 seed = 629055291 rfd = 216 debug_save = 0 debug_flag = 0 dont_fork_cnt = 0 n_lst = 0x0 p = 0x5 <Address 0x5 out of bounds> __FUNCTION__ = "main" --------------------------------------------------------------------------------------------------------- It seems the socket got error (uncertain) Does anyone have the similar experience? Thanks --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/466

8 years, 3 months

[kamailio] [4.2.6] kamailio does not work when Recv-Q is high... (#510)

by BroWater

Steps: (1) 9 users and 1 mcu registered the server (2) All 9 users connect to the mcu through the server (3) For a while, some users showed registration fail, we found Kamailio does not respond to any further SIP message. I checked this using `netstat -apn` and it showed: ``` Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name tcp 0 0 10.100.92.42:5060 0.0.0.0:* LISTEN 9384/kamailio tcp 0 0 10.100.92.42:5061 0.0.0.0:* LISTEN 9384/kamailio tcp 48963 0 10.100.92.42:5060 10.100.92.42:42177 ESTABLISHED 9375/kamailio tcp 29621 0 10.100.92.42:5060 10.100.92.40:34392 ESTABLISHED 9375/kamailio tcp 1617 0 10.100.92.42:43997 10.100.92.42:5062 ESTABLISHED 9375/kamailio tcp 2506 0 10.100.92.42:5060 10.100.92.68:37386 ESTABLISHED 9383/kamailio udp 0 0 127.0.0.1:50180 127.0.0.1:7722 ESTABLISHED 9384/kamailio udp 0 0 127.0.0.1:47125 127.0.0.1:7722 ESTABLISHED 9378/kamailio udp 0 0 127.0.0.1:55318 127.0.0.1:7722 ESTABLISHED 9375/kamailio udp 0 0 127.0.0.1:44595 127.0.0.1:7722 ESTABLISHED 9382/kamailio udp 0 0 127.0.0.1:41045 127.0.0.1:7722 ESTABLISHED 9383/kamailio udp 0 0 127.0.0.1:52901 127.0.0.1:7722 ESTABLISHED 9380/kamailio udp 0 0 127.0.0.1:39078 127.0.0.1:7722 ESTABLISHED 9376/kamailio udp 0 0 127.0.0.1:36548 127.0.0.1:7722 ESTABLISHED 9377/kamailio udp 0 0 127.0.0.1:55005 127.0.0.1:7722 ESTABLISHED 9374/kamailio udp 0 0 127.0.0.1:53049 127.0.0.1:7722 ESTABLISHED 9381/kamailio udp 0 0 127.0.0.1:44358 127.0.0.1:7722 ESTABLISHED 9374/kamailio udp 0 0 127.0.0.1:60253 127.0.0.1:7722 ESTABLISHED 9379/kamailio udp 0 0 10.100.92.42:5060 0.0.0.0:* 9374/kamailio udp 0 0 ::ffff:127.0.0.1:7722 :::* 9354/rtpengine raw 0 0 0.0.0.0:255 0.0.0.0:* 255 9374/kamailio ``` And I attached the gdb to the those processed which Recv-Q value were high, it showed: PID [9375] ``` #0 0x400a7450 in recvfrom () from /lib/libpthread.so.0 No symbol table info available. #1 0x00265848 in udp_rcv_loop () at udp_server.c:446 len = 472 buf = "REGISTER sip:10.100.92.42:5060 SIP/2.0\r\nVia: SIP/2.0/UDP 10.100.91.13:5060;rport;branch=z9hG4bK649620372\r\nFrom: <sip:DEMOROOM_2@10.100.92.42>;tag=658334621\r\nTo: <sip:DEMOROOM_2@10.100.92.42>\r\nCall-ID:"... tmp = 0x49fbfc "10.100.92.43" from = 0x402d5b24 fromlen = 16 ri = {src_ip = {af = 2, len = 4, u = {addrl = {224093194, 3200776404, 1935216, 1734536862}, addr32 = {224093194, 3200776404, 1935216, 1734536862}, addr16 = {25610, 3419, 63700, 48839, 34672, 29, 61086, 26466}, addr = "\nd[\r\324\370\307\276p\207\035\000\236\356bg"}}, dst_ip = {af = 2, len = 4, u = {addrl = {710697994, 0, 0, 0}, addr32 = {710697994, 0, 0, 0}, addr16 = {25610, 10844, 0, 0, 0, 0, 0, 0}, addr = "\nd\\*", '\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\304\nd[\r\000\000\000\000\000\000\000"}, sin = { sin_family = 2, sin_port = 50195, sin_addr = {s_addr = 224093194}, sin_zero = "\000\000\000\000\000\000\000"}, sin6 = {sin6_family = 2, sin6_port = 50195, sin6_flowinfo = 224093194, 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 = 0x40261e14, proto = 1 '\001'} __FUNCTION__ = "udp_rcv_loop" #2 0x000c90a0 in main_loop () at main.c:1629 i = 0 pid = 0 si = 0x40261e14 si_desc = "udp receiver child=0 sock=10.100.92.42:5060\000\244\372\307\276\304y2A\000\000,\000\000\000X\000 \000\062@ \313'@h\t;\000\230\325&@\000\000\000\000x\021\002\000\060K1@\000\000\000\000d\372\307\276\260\275'\000\230\331\070\000X\311\070\000\030\000\000\000\000PVA\000\000\000\000\000\000\a@Lu\223A" r = 1074200576 nrprocs = 1 __FUNCTION__ = "main_loop" #3 0x000d0830 in main (argc=5, argv=0xbec7fd04) at main.c:2581 cfg_stream = 0x4b5008 c = -1 r = 0 tmp = 0x40070ac4 "P\002\006@\a" tmp_len = -1094190232 port = 1074045560 proto = -1094190164 options = 0x383880 ":f:cm:M:dVIhEeb:l:L:n:vKrRDTN:W:w:t:u:g:P:G:SQ:O:a:A:" ret = -1 seed = 3985271057 rfd = 206 debug_save = 0 debug_flag = 0 dont_fork_cnt = 0 n_lst = 0x0 p = 0x5 <Address 0x5 out of bounds> __FUNCTION__ = "main" ``` PID [9383] ``` #0 0x400a6a04 in __lll_lock_wait () from /lib/libpthread.so.0 #1 0x400a10b8 in pthread_mutex_lock () from /lib/libpthread.so.0 #2 0x002145b4 in tcpconn_do_send (fd=21, c=0x41969534, buf=0x402cbcb0 "SIP/2.0 200 Keepalive\r\nVia: SIP/2.0/TCP 10.100.92.42:5062;rport=42177;branch=z9hG4bK416855936;received=10.100.92.42\r\nFrom: <sip:10.100.92.42:42177>;tag=1996784611\r\nTo: <sip:10.100.92.42:5060>;tag=e926"..., len=340, send_flags=..., resp=0xbec7daf8, locked=0) at tcp_main.c:2520 #3 0x002130f0 in tcpconn_send_put (c=0x41969534, buf=0x402cbcb0 "SIP/2.0 200 Keepalive\r\nVia: SIP/2.0/TCP 10.100.92.42:5062;rport=42177;branch=z9hG4bK416855936;received=10.100.92.42\r\nFrom: <sip:10.100.92.42:42177>;tag=1996784611\r\nTo: <sip:10.100.92.42:5060>;tag=e926"..., len=340, send_flags=...) at tcp_main.c:2377 #4 0x002105c0 in tcp_send (dst=0xbec7e38c, from=0x0, buf=0x402cbcb0 "SIP/2.0 200 Keepalive\r\nVia: SIP/2.0/TCP 10.100.92.42:5062;rport=42177;branch=z9hG4bK416855936;received=10.100.92.42\r\nFrom: <sip:10.100.92.42:42177>;tag=1996784611\r\nTo: <sip:10.100.92.42:5060>;tag=e926"..., len=340) at tcp_main.c:2133 #5 0x40e33164 in msg_send (dst=0xbec7e38c, buf=0x402cbcb0 "SIP/2.0 200 Keepalive\r\nVia: SIP/2.0/TCP 10.100.92.42:5062;rport=42177;branch=z9hG4bK416855936;received=10.100.92.42\r\nFrom: <sip:10.100.92.42:42177>;tag=1996784611\r\nTo: <sip:10.100.92.42:5060>;tag=e926"..., len=340) at ../../forward.h:225 #6 0x40e362e8 in sl_reply_helper (msg=0x40342814, code=200, reason=0x402e1d6c "Keepalive", tag=0x0) at sl_funcs.c:224 #7 0x40e373e0 in sl_send_reply (msg=0x40342814, code=200, reason=0x402e1d6c "Keepalive") at sl_funcs.c:327 #8 0x40e2b30c in w_sl_send_reply (msg=0x40342814, p1=0x40282108 "\244\324(@\001", p2=0x40282140 "d\320(@ ") at sl.c:236 #9 0x00029b90 in do_action (h=0xbec7f110, a=0x4028cf34, msg=0x40342814) at action.c:1100 #10 0x00036ce8 in run_actions (h=0xbec7f110, a=0x4028cf34, msg=0x40342814) at action.c:1583 #11 0x00029a2c in do_action (h=0xbec7f110, a=0x4028d120, msg=0x40342814) at action.c:1079 #12 0x00036ce8 in run_actions (h=0xbec7f110, a=0x40288ed8, msg=0x40342814) at action.c:1583 #13 0x000263ac in do_action (h=0xbec7f110, a=0x40279db8, msg=0x40342814) at action.c:712 #14 0x00036ce8 in run_actions (h=0xbec7f110, a=0x4027321c, msg=0x40342814) at action.c:1583 #15 0x00037538 in run_top_route (a=0x4027321c, msg=0x40342814, c=0x0) at action.c:1669 #16 0x0013ce80 in receive_msg ( buf=0x41969714 "OPTIONS sip:10.100.92.42:5060 SIP/2.0\r\nVia: SIP/2.0/TCP 10.100.92.42:5062;rport;branch=z9hG4bK416855936\r\nFrom: <sip:10.100.92.42:42177>;tag=1996784611\r\nTo: <sip:10.100.92.42:5060>\r\nCall-ID: 2059319404"..., len=404, rcv_info=0x4196955c) at receive.c:216 #17 0x00248fbc in receive_tcp_msg ( tcpbuf=0x41969714 "OPTIONS sip:10.100.92.42:5060 SIP/2.0\r\nVia: SIP/2.0/TCP 10.100.92.42:5062;rport;branch=z9hG4bK416855936\r\nFrom: <sip:10.100.92.42:42177>;tag=1996784611\r\nTo: <sip:10.100.92.42:5060>\r\nCall-ID: 2059319404"..., len=404, rcv_info=0x4196955c, con=0x41969534) at tcp_read.c:1230 #18 0x0024c484 in tcp_read_req (con=0x41969534, bytes_read=0xbec7f714, read_flags=0xbec7f70c) at tcp_read.c:1431 #19 0x0024fdd8 in handle_io (fm=0x403321ac, events=1, idx=-1) at tcp_read.c:1655 #20 0x0023f814 in io_wait_loop_epoll (h=0x47d90c, t=2, repeat=0) at io_wait.h:1073 #21 0x00250fc8 in tcp_receive_loop (unix_sock=22) at tcp_read.c:1764 #22 0x00234674 in tcp_init_children () at tcp_main.c:4879 #23 0x000c9dec in main_loop () at main.c:1714 #24 0x000d0830 in main (argc=5, argv=0xbec7fd04) at main.c:2581 ``` In process 9375, I found it got stuck in recvfrom () (udp_server.c:446), and the code is: `len=recvfrom(bind_address->socket, buf, BUF_SIZE, 0, &from->s,&fromlen);` It seems operate using blocking mode instead of a non-blocking one, why??? Or maybe I miss a packet-check function like select() or poll()?? Thanks in advance. :) --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/510

8 years, 3 months

[kamailio] uac module (uac_req_send, register) doesn't do failover (#210)

by Alex

Requests send with uac module's functions like uac_req_send() and remote registrations use the TM module, but don't actually do (DNS) failover when a destination is unreachable or sends a 503. In the tm's uac.c, t_uac_prepare() function, i can already see that the dns handle is stored in a throwaway variable instead of in the transaction, but fixing the issue isn't as simple as just storing it in the transaction (print_uac_request_from_buf() needs a uas in the transaction). Due to github not supporting attachments to issues, the sample config and debug log are inline.... :( DNS entry: ``` $ host -t SRV _sip._udp.failover.test.speakup.nl _sip._udp.failover.test.speakup.nl has SRV record 10 0 1111 try1.test.speakup.nl. _sip._udp.failover.test.speakup.nl has SRV record 100 0 2222 try2.test.speakup.nl. ``` Log snippet: ```syslog Jun 15 10:00:03 rio UACFailover[18178]: INFO: <script>: Sending OPTIONS request to sip:failover.test.speakup.nl Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:failover.test.speakup.nl> Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (_sip._udp.failover.test.speakup.nl(34), 33), h=825 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [resolve.c:937]: get_record(): skipping 8 NS (p=0x9ea284, end=0x9ea3e9) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [resolve.c:952]: get_record(): parsing 9 ARs (p=0x9ea335, end=0x9ea3e9) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:1741]: dns_get_related(): (0x7f225233eb38 (_sip._udp.failover.test.speakup.nl, 33), 33, *0x7f2257baf2b8) (0) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:840]: dns_cache_add_unsafe(): adding _sip._udp.failover.test.speakup.nl(34) 33 (flags=0) at 825 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:840]: dns_cache_add_unsafe(): adding try1.test.speakup.nl(20) 1 (flags=0) at 310 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:840]: dns_cache_add_unsafe(): adding try2.test.speakup.nl(20) 1 (flags=0) at 307 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:2336]: dns_srv_get_nxt_rr(): (0x7f225233eb38, 0, 0, 1597643831): selected 0/1 in grp. 0 (rand_w=0, rr=0x7f225233eba0 rd=0x7f225233ebb8 p=10 w=0 rsum=0) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:537]: _dns_hash_find(): (try1.test.speakup.nl(20), 1), h=310 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:2926]: dns_a_resolve(): (try1.test.speakup.nl, 0) returning 0 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:3169]: dns_srv_resolve_ip(): ("_sip._udp.failover.test.speakup.nl", 0, 0), ret=0, ip=192.168.1.245 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [dns_cache.c:3260]: dns_srv_sip_resolve(): (failover.test.speakup.nl, 0, 0), srv0, ret=0 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 54501 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: tm [uac.c:351]: t_uac_prepare(): executing event_route[tm:local-request] Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request: Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method: <OPTIONS> Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: <sip:failover.test.speakup.nl> Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version: <SIP/2.0> Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK5e4d.47adbbf5000000000000000000000000.0>; state=16 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10 Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [22]; uri=[sip:to.example.com] Jun 15 10:00:03 rio UACFailover[18178]: [97B blob data] Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <10> <OPTIONS> Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: pv [pv_core.c:376]: pv_get_xto_attr(): no Tag parameter Jun 15 10:00:03 rio UACFailover[18178]: INFO: <script>: [udp:172.28.4.128:6789] [10 OPTIONS] Request (cid: 6cd531e47cc63e20-18178(a)172.28.4.128 Branch: -1 ToTag: <null> len:382) To <sip:failover.test.speakup.nl> via <sip:failover.test.speakup.nl> Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jun 15 10:00:03 rio UACFailover[18178]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Jun 15 10:00:05 rio UACFailover[18176]: DEBUG: tm [t_reply.c:1230]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=408 Jun 15 10:00:05 rio UACFailover[18176]: WARNING: tm [t_reply.c:957]: run_failure_handlers(): Warning: run_failure_handlers: no UAC support (1, 0) Jun 15 10:00:05 rio UACFailover[18176]: DEBUG: tm [t_reply.c:2016]: local_reply(): DEBUG: local_reply: branch=0, save=0, winner=0 Jun 15 10:00:05 rio UACFailover[18176]: DEBUG: tm [t_reply.c:2053]: local_reply(): DEBUG: local transaction completed ``` Sample config: ```python #------------------ # Flags #------------------ debug=3 memdbg=5 mem_summary=5 #fork=no # This option should not be present to enable forking but disable daemonize, also -D commandline parameter is needed log_stderror=no sip_warning=no listen=172.28.4.128 port=6789 children=2 shm_mem_size=64 log_name="UACFailover" check_via=no # (cmd. line: -v) dns=no # (cmd. line: -r) rev_dns=no # (cmd. line: -R) dns_use_search_list=no use_dns_failover=yes dns_srv_lb=yes disable_tcp=yes mpath="/usr/lib/x86_64-linux-gnu/kamailio/modules/" loadmodule "pv.so" loadmodule "tm.so" loadmodule "tmx.so" loadmodule "sl.so" loadmodule "xlog.so" loadmodule "rr.so" loadmodule "uac.so" loadmodule "rtimer.so" # ----------------------------------------------------------------- # Module settings # ----------------------------------------------------------------- modparam("pv", "varset", "server=s:LOGNAME") modparam("tm", "auto_inv_100", 0) # Time until provisional response (eg "100 Trying") is received modparam("tm", "fr_timer", 2000) # Time to await final response on INVITE per branch (eg per branch ring time) modparam("tm", "fr_inv_timer", 10000) # Time to await final response on INVITE (eg ring time) modparam("tm", "max_inv_lifetime", 20000) modparam("tm", "restart_fr_on_each_reply", 0) modparam("tm", "failure_reply_mode", 3) modparam("rtimer", "timer", "name=uac;interval=10;mode=1;") modparam("rtimer", "exec", "timer=uac;route=TIMER") route { xlog("L_NOTICE", "[$pr:$si:$sp] [$cs $rm] Request. (cid: $ci <$fu> => <$ru> len: $ml)"); drop(); } event_route[tm:local-request] { xlog("L_INFO", " [$pr:$si:$sp] [$cs $rm] Request (cid: $ci Branch: $T_branch_idx ToTag: $tt len:$ml) To <$ru> via <$du>"); } onreply_route { xlog("L_INFO", " [$pr:$si:$sp] [$cs $rm] Reply (cid: $ci Branch: $T_branch_idx Status: $rs $rr ToTag: $tt len:$ml)"); } route[TIMER] { if not $var(done) == 1 { $var(done) = 1; $uac_req(method) = "OPTIONS"; $uac_req(ruri) = "sip:failover.test.speakup.nl"; $uac_req(furi) = "sip:from.example.com"; $uac_req(turi) = "sip:to.example.com"; xlog("L_INFO", "Sending $uac_req(method) request to $uac_req(ruri)"); uac_req_send(); } } ``` --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/210

8 years, 3 months

git:master:b7c588df: tm: store last received response code before running onreply_route

by Daniel-Constantin Mierla

Module: kamailio Branch: master Commit: b7c588dff06030a82f305b210573be5bbc961cec URL: https://github.com/kamailio/kamailio/commit/b7c588dff06030a82f305b210573be5… Author: Daniel-Constantin Mierla <miconda(a)gmail.com> Committer: Daniel-Constantin Mierla <miconda(a)gmail.com> Date: 2015-09-07T15:56:45+02:00 tm: store last received response code before running onreply_route - otherwise an innapropriate cancel can happen if the current branch got before an 1xx response, and the script writer enforces another reply code with t_reply() - reported by Thomas Sevestre, GH#315 --- Modified: modules/tm/t_reply.c --- Diff: https://github.com/kamailio/kamailio/commit/b7c588dff06030a82f305b210573be5… Patch: https://github.com/kamailio/kamailio/commit/b7c588dff06030a82f305b210573be5… --- diff --git a/modules/tm/t_reply.c b/modules/tm/t_reply.c index b2e6e53..8da3205 100644 --- a/modules/tm/t_reply.c +++ b/modules/tm/t_reply.c @@ -2286,10 +2286,21 @@ int reply_received( struct sip_msg *p_msg ) backup_xavps = xavp_set_list(&t->xavps_list); #endif setbflagsval(0, uac->branch_flags); + if(msg_status>last_uac_status) { + /* current response (msg) status is higher that the last received + * on the same branch - set it temporarily so functions in onreply_route + * can access it (e.g., avoid sending CANCEL by forcing another t_relply() + * in onreply_route when a negative sip response was received) */ + uac->last_received = msg_status; + } + /* Pre- and post-script callbacks have already * been executed by the core. (Miklos) */ run_top_route(onreply_rt.rlist[onreply_route], p_msg, &ctx); + + /* restore brach last_received as before executing onreply_route */ + uac->last_received = last_uac_status; /* transfer current message context back to t */ if (t->uas.request) t->uas.request->flags=p_msg->flags; getbflagsval(0, &uac->branch_flags);

8 years, 4 months

[kamailio] Module:sca Line seize does not release after some 'bye's (#341)

by Kevin Scott Adams

I have a situation that I can't get my head around. Every so often I have a SCA seized and I need to manually release it. Here is the log of the sca and bye from the event logs. messages-20150920:Sep 18 14:35:28 sip-registrar /usr/sbin/kamailio[7608]: ERROR: sca [sca_call_info.c:1580]: sca_call_info_bye_handler(): sca_call_info_bye_handler: sip:Mindfield@kamailio.newgarllc.com dialog leg 96b9ad37-9a7fa462-15c35d3(a)192.168.1.117;1c389191866 is not active messages-20150920:Sep 18 14:35:28 sip-registrar /usr/sbin/kamailio[7610]: NOTICE: acc [acc.c:317]: acc_log_request(): ACC: transaction answered: timestamp=1442601328;method=BYE;from_tag=76C3EC1F-114D759A;to_tag=1c389191866;call_id=96b9ad37-9a7fa462-15c35d3(a)192.168.1.117;code=200;reason=OK;src_user=Mindfield;src_domain=kamailio.newgarllc.com;src_ip=192.168.1.40;dst_ouser=Mindfield;dst_user=Mindfield;dst_domain=192.168.1.117 And the kamcmd showing the seize line (in this case both are seized) sip:Mindfield@kamailio.newgarllc.com 1 active 1442601278 sip:Mindfield@192.168.1.117 sip:1030@192.168.1.40:5060 96b9ad37-9a7fa462-15c35d3(a)192.168.1.117 76C3EC1F-114D759A 1c389191866 I am running Kamailio 4.2.6. The phones are Polycom VVX 310/410 running 5.3.1. And the PSTN gateway is an Audiocodes Mediant 1000 running 6.60A.292.001. The call volume is light so I don't think there is a lot of traffic but there is about 16 phones one SCA. It is not happening all time. Could be a couple days that nothing happens and then there could be a couple days that it happens multiple times a day. I can't find a common element that would be causing this unless the 'bye' is closing the call before the SCA has a chance to do something with it. Thanks Kev --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/341

8 years, 5 months

[kamailio] SNMPstats dialog 0 afeter reboot (#424)

by igorPot

I have rebooted my kamailio machine and now the value of kamailio dialogs is always 0: KAMAILIO-MIB::kamailioCurNumDialogs.0 = Gauge32: 0 KAMAILIO-MIB::kamailioCurNumDialogsInProgress.0 = Gauge32: 0 KAMAILIO-MIB::kamailioCurNumDialogsInSetup.0 = Gauge32: 0 KAMAILIO-MIB::kamailioTotalNumFailedDialogSetups.0 = Counter32: 0 And also the status is: KAMAILIO-MIB::kamailioDialogUsageState.0 = INTEGER: idle(0) How can I change it to active(1)? I have dialog.so loaded as well as modparam("dialog", "dlg_flag", 4) and dlg_manage(). If I do 'kamctl stats dialog': # kamctl stats dialog dialog:active_dialogs = 498 dialog:early_dialogs = 57 dialog:expired_dialogs = 24 dialog:failed_dialogs = 73222 dialog:processed_dialogs = 539449 I have the same configruation of SNMPstats and kamailio in 2 other machines and it works perfectly. In this machine it worked until I rebooted the machine. I asked this question in th emailing list and I got the following response: "Must be something in how the SNMP module reads the data from the statistics counters." I don't know if this could help. Thank you. --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/424

8 years, 6 months

[kamailio] Lots of tcp_read_* errors in log, multi-transport server, lack of context in messages (#385)

by Andrey Utkin

Hi devs, The logs of our Kamailio servers are rich for such lines: ``` Oct 28 06:57:35 ip-172-31-0-139 /usr/local/sbin/kamailio[31963]: ERROR: <core> [tcp_read.c:271]: tcp_read_data(): error reading: Connection reset by peer (104) Oct 28 06:57:35 ip-172-31-0-139 /usr/local/sbin/kamailio[31963]: ERROR: <core> [tcp_read.c:1296]: tcp_read_req(): ERROR: tcp_read_req: error reading ``` The servers serve WS, WSS, UDP, TCP and TLS as connection transports. So we cannot figure what happens and if this is really a problem, but we get accused by whoever audits the servers. What could be done to make this more trackable? Or maybe these messages should be suppressed, if there's no way to track them and they don't mean any problem? --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/385

8 years, 6 months

[kamailio] MS ODBC Driver crashes kamailio on boot (#501)

by Tim Chubb

Hi im currently using freetds via unixodbc to connect to a sql azure db which is ok but suffers from various connection busy errors. I would like to use the ms odbc driver as it supports MARS (multiple active result sets) which should stop the connection busy errors, and numerous other sql server specific enhancements However despite the odbc driver working fine with the unixodbc cli tools kamailio crashes when I try and start the service. --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/501

8 years, 6 months

[kamailio] cnxcc: segmentation fault on 2 calls get terminatet - kamailio 4.3.3 (#417)

by Rick

Log: ``` Nov 25 12:01:23 sip42 kamailio[29215]: INFO: <script>: start - duration: 0 Nov 25 12:01:24 sip42 kamailio[29234]: INFO: cnxcc [cnxcc_check.c:93]: check_calls_by_money(): ec=0.000000, ca=1.000000, ca2=0.000000 Nov 25 12:01:24 sip42 kamailio[29218]: INFO: <script>: start - duration: 0 Nov 25 12:01:25 sip42 kamailio[29234]: INFO: cnxcc [cnxcc_check.c:93]: check_calls_by_money(): ec=0.000000, ca=4.000000, ca2=1.000000 Nov 25 12:01:26 sip42 kamailio[29234]: INFO: cnxcc [cnxcc_check.c:93]: check_calls_by_money(): ec=0.000000, ca=7.000000, ca2=4.000000 Nov 25 12:01:27 sip42 kamailio[29234]: INFO: cnxcc [cnxcc_check.c:93]: check_calls_by_money(): ec=0.000000, ca=9.000000, ca2=7.000000 Nov 25 12:01:28 sip42 kamailio[29234]: INFO: cnxcc [cnxcc_check.c:93]: check_calls_by_money(): ec=0.000000, ca=11.000000, ca2=9.000000 Nov 25 12:01:28 sip42 kamailio[29234]: INFO: <script>: [2JR.9RLbmcERvjSelQ5zHRdAONs1pk0V]: call killed Nov 25 12:01:28 sip42 kamailio[29234]: INFO: <script>: [73tmL4-hk3-50vnsgt19E4rPMMAqHlAG]: call killed Nov 25 12:01:28 sip42 kamailio[29234]: ERROR: cnxcc [cnxcc_mod.c:1023]: terminate_call(): Error executing dlg_end_dlg command. Return code was [404] Nov 25 12:01:28 sip42 kamailio[29246]: CRITICAL: <core> [pass_fd.c:275]: receive_fd(): EOF on 45 Nov 25 12:01:28 sip42 kamailio[29187]: ALERT: <core> [main.c:728]: handle_sigs(): child process 29234 exited by a signal 11 Nov 25 12:01:28 sip42 kamailio[29187]: ALERT: <core> [main.c:731]: handle_sigs(): core was not generated Nov 25 12:01:28 sip42 kamailio[29187]: INFO: <core> [main.c:743]: handle_sigs(): terminating due to SIGCHLD ``` Stacktrace: ``` (gdb) bt full #0 0x00007f3e25b5cfba in get_hash1_raw (s=0x0, len=0) at ../../hashes.h:109 p = 0x0 v = 4284224 h = 0 #1 0x00007f3e25b5d22d in str_hash_get (ht=0x7f3e26333580, key=0x0, len=0) at ../../str_hash.h:94 h = 32766 e = 0x301c31c78 #2 0x00007f3e25b69167 in __free_call (call=0x7f3e2646da00) at cnxcc_mod.c:910 e = 0x0 __FUNCTION__ = "__free_call" #3 0x00007f3e25b68dd5 in terminate_all_calls (credit_data=0x7f3e2646d950) at cnxcc_mod.c:899 call = 0x7f3e2646da00 tmp = 0x7f3e2646da00 __FUNCTION__ = "terminate_all_calls" #4 0x00007f3e25b5952f in check_calls_by_money (ticks=51698976, param=0x0) at cnxcc_check.c:109 credit_data = 0x7f3e2646d950 call = 0x7f3e2646da00 total_consumed_money = 11 consumption_diff = 2 h_entry = 0x7f3e2646d858 tmp = 0x7f3e26335c00 tmp_call = 0x7f3e2647d680 i = 118 __FUNCTION__ = "check_calls_by_money" #5 0x00000000005bcf1c in fork_basic_timer (child_id=-1, desc=0x7f3e25b866fd "CNXCC TB TIMER", make_sock=1, f=0x7f3e25b58200 <check_calls_by_money>, param=0x0, interval=1) at timer_proc.c:79 pid = 0 #6 0x00007f3e25b5ffce in __child_init (rank=0) at cnxcc_mod.c:383 pid = 0 __FUNCTION__ = "__child_init" #7 0x00000000005143d4 in init_mod_child (m=0x7f3e315cf8a8, rank=0) at sr_module.c:915 __FUNCTION__ = "init_mod_child" #8 0x00000000005146db in init_child (rank=0) at sr_module.c:941 No locals. #9 0x0000000000505cef in main_loop () at main.c:1654 i = 4 pid = 1327 si = 0x0 si_desc = "udp receiver child=3 sock=127.0.0.1:5060\000\061\000*>\177\000\000\240.~\264\376\177\000\000\a<\355*>\177\000\000y%p\000\000\000\000\000ȥ3&>\177\000\000\220$\361*>\177\000\000\300(l1>\177\000\000\000\371\360*>\177\000\000\003\000\000\000>\177\000\000\060.~\264\376\177\000\000xBI\000\000\000\000" nrprocs = 4 __FUNCTION__ = "main_loop" #10 0x000000000050be5a in main (argc=13, argv=0x7ffeb47e3138) at main.c:2553 cfg_stream = 0x1b9c010 c = -1 r = 0 tmp = 0x7ffeb47e4ee2 "" tmp_len = 32574 port = 846456272 proto = 0 options = 0x71cae0 ":f:cm:M:dVIhEeb:l:L:n:vKrRDTN:W:w:t:u:g:P:G:SQ:O:a:A:" ret = -1 seed = 1760074178 rfd = 4 debug_save = 0 debug_flag = 0 dont_fork_cnt = 0 n_lst = 0xf63d4e2e p = 0x7f3e3273e000 "" st = {st_dev = 15, st_ino = 9426, st_nlink = 2, st_mode = 16877, st_uid = 109, st_gid = 114, __pad0 = 0, st_rdev = 0, st_size = 40, st_blksize = 4096, st_blocks = 0, st_atim = {tv_sec = 1448451190, tv_nsec = 195999907}, st_mtim = {tv_sec = 1448451190, tv_nsec = 195999907}, st_ctim = {tv_sec = 1448451190, tv_nsec = 195999907}, __glibc_reserved = {0, 0, 0}} __FUNCTION__ = "main" ``` --- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/417

8 years, 6 months

Jump to page:

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

sr-dev March 2016