- sr-users - kamailio.org

CRITICAL: <core> [core/data_lump.c:292]: del_lump(): offset exceeds message size (1436 > 1432) aborting...
by Denys Pozniak 27 May '20

27 May '20

Hello! Could you please check the reason of crash below? syslog: kamailio[31738]: CRITICAL: |RGFLAFL63U|<core> [core/data_lump.c:292]: del_lump(): offset exceeds message size (1436 > 1432) aborting... gdb ( uri and IP were modified ): Core was generated by `/usr/sbin/kamailio -f /etc/kamailio/kamailio.cfg -P /var/run/kamailio/kamailio.'. Program terminated with signal 6, Aborted. #0 0x00007f5d8556d4f5 in raise () from /lib64/libc.so.6 Missing separate debuginfos, use: debuginfo-install cyrus-sasl-lib-2.1.23-15.el6_6.2.x86_64 glibc-2.12-1.212.el6.x86_64 keyutils-libs-1.4-5.el6.x86_64 krb5-libs-1.10.3-65.el6.x86_64 libcom_err-1.41.12-24.el6.x86_64 libcurl-7.19.7-53.el6_9.x86_64 libidn-1.18-2.el6.x86_64 libselinux-2.0.94-7.el6.x86_64 libssh2-1.4.2-2.el6_7.1.x86_64 mysql-libs-5.1.73-8.el6_8.x86_64 nspr-4.19.0-1.el6.x86_64 nss-3.36.0-9.el6_10.x86_64 nss-softokn-freebl-3.14.3-23.3.el6_8.x86_64 nss-util-3.36.0-1.el6.x86_64 openldap-2.4.40-16.el6.x86_64 openssl-1.0.1e-58.el6_10.x86_64 zlib-1.2.3-29.el6.x86_64 (gdb) bt #0 0x00007f5d8556d4f5 in raise () from /lib64/libc.so.6 #1 0x00007f5d8556ecd5 in abort () from /lib64/libc.so.6 #2 0x000000000046a4a6 in del_lump (msg=0x7f5d846cd798, offset=1436, len=29, type=HDR_OTHER_T) at core/data_lump.c:293 #3 0x00007f5d7e6fd7f5 in alter_rtcp (msg=0x7f5d846cd798, body=0x7fffc60c8600, oldport=0x7f5d84715500, newport=0x7fffc60c8550) at rtpproxy.c:1229 #4 0x00007f5d7e70ec5d in force_rtp_proxy (msg=0x7f5d846cd798, str1=0x7f5d846af190 "co", str2=0x7fffc60c87f0 "162.221.21.250", offer=1, forcedIP=0) at rtpproxy.c:2669 #5 0x00007f5d7e705f64 in rtpproxy_manage (msg=0x7f5d846cd798, flags=0x7f5d846af190 "co", ip=0x0) at rtpproxy.c:1862 #6 0x00007f5d7e7064c6 in rtpproxy_manage1 (msg=0x7f5d846cd798, flags=0x7f5d846907f0 "\220\361j\204]\177", ip=0x0) at rtpproxy.c:1904 #7 0x0000000000434f19 in do_action (h=0x7fffc60cae70, a=0x7f5d846b16d8, msg=0x7f5d846cd798) at core/action.c:1073 #8 0x0000000000441a94 in run_actions (h=0x7fffc60cae70, a=0x7f5d846b16d8, msg=0x7f5d846cd798) at core/action.c:1564 #9 0x0000000000431663 in do_action (h=0x7fffc60cae70, a=0x7f5d8468ae40, msg=0x7f5d846cd798) at core/action.c:691 #10 0x0000000000441a94 in run_actions (h=0x7fffc60cae70, a=0x7f5d846848a0, msg=0x7f5d846cd798) at core/action.c:1564 #11 0x0000000000431663 in do_action (h=0x7fffc60cae70, a=0x7f5d846abca0, msg=0x7f5d846cd798) at core/action.c:691 #12 0x0000000000441a94 in run_actions (h=0x7fffc60cae70, a=0x7f5d846abca0, msg=0x7f5d846cd798) at core/action.c:1564 #13 0x0000000000434df4 in do_action (h=0x7fffc60cae70, a=0x7f5d846abdf0, msg=0x7f5d846cd798) at core/action.c:1058 #14 0x0000000000441a94 in run_actions (h=0x7fffc60cae70, a=0x7f5d846a45f8, msg=0x7f5d846cd798) at core/action.c:1564 #15 0x0000000000442190 in run_top_route (a=0x7f5d846a45f8, msg=0x7f5d846cd798, c=0x0) at core/action.c:1646 #16 0x0000000000546d87 in receive_msg ( buf=0x29463b0 "INVITE sip:999@18.12.188.108 SIP/2.0\r\nVia: SIP/2.0/TCP 10.0.0.33:49631;branch=z9hG4bK.9XHFYH0zF;rport\r\nFrom: < sip:RGFLAFL63U@proxy.company.com>;tag=DcTh-~6~M\r\nTo: \"Liz - Home Office\" <s"..., len=1432, rcv_info=0x7f5d5e3ae778) at core/receive.c:340 #17 0x000000000064807d in receive_tcp_msg ( tcpbuf=0x7f5d5e3aea58 "INVITE sip:999@18.12.188.108 SIP/2.0\r\nVia: SIP/2.0/TCP 10.0.0.33:49631;branch=z9hG4bK.9XHFYH0zF;rport\r\nFrom: < sip:RGFLAFL63U@proxy.company.com>;tag=DcTh-~6~M\r\nTo: \"Liz - Home Office\" <s"..., len=1432, rcv_info=0x7f5d5e3ae778, con=0x7f5d5e3ae760) at core/tcp_read.c:1448 #18 0x000000000064a0fd in tcp_read_req (con=0x7f5d5e3ae760, bytes_read=0x7fffc60cb8ac, read_flags=0x7fffc60cb8a4) at core/tcp_read.c:1631 #19 0x000000000064dcd3 in handle_io (fm=0x7f5d846ecda0, events=1, idx=-1) at core/tcp_read.c:1862 #20 0x000000000063b7c4 in io_wait_loop_epoll (h=0xacd340, t=2, repeat=0) at core/io_wait.h:1065 #21 0x000000000064f133 in tcp_receive_loop (unix_sock=51) at core/tcp_read.c:1974 #22 0x00000000006318c8 in tcp_init_children () at core/tcp_main.c:4853 #23 0x0000000000425db5 in main_loop () at main.c:1745 #24 0x000000000042c45e in main (argc=13, argv=0x7fffc60cc0e8) at main.c:2696 (gdb) CentOS release 6.10 (Final) version: kamailio 5.2.2 (x86_64/linux) 67f967 flags: STATS: Off, 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_URI_SIZE 1024, BUF_SIZE 65535, DEFAULT PKG_SIZE 8MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, select. id: 67f967 compiled on 11:39:47 Mar 11 2019 with gcc 4.4.7 -- BR, Denys Pozniak

2 1

CANCEL relayed to broadcast 255.255.255.255
by Sergiu Pojoga 27 May '20

27 May '20

Hi gents, Got this weird "problem", where CANCEL request is relayed to both the right destination as well as the 255.255.255.255 broadcast address!? I have no such logic in my script, that's for sure. I doesn't do anything harmful (as far as I can tell), but I'm just curious why it's doing that. Any ideas? version: kamailio 5.3.4 *SIP Trace:* U 2020/05/26 08:40:10.941722 PBXIP:5060 -> KAMAILIO_IP:5060 CANCEL sip:2383@DOWNSTREAM_IP:10533;transport=TLS SIP/2.0 Via: SIP/2.0/UDP PBXIP:5060;branch=z9hG4bK450689d6 Max-Forwards: 69 From: "some callerid" <sip:18001111111@mypbx.mydomain.com>;tag=as4d223616 To: <sip:2383@DOWNSTREAM_IP:10533;transport=TLS> Call-ID: 1511475b69f6379b08f04d110f534da1(a)mypbx.mydomain.com CSeq: 102 CANCEL User-Agent: B2B Content-Length: 0 U 2020/05/26 08:40:10.941799 KAMAILIO_IP:5060 -> 255.255.255.255:5060 CANCEL sip:2383@DOWNSTREAM_IP:10533;transport=TLS SIP/2.0 Via: SIP/2.0/UDP PBXIP:5060;branch=z9hG4bK450689d6 Max-Forwards: 69 From: "some callerid" <sip:18001111111@mypbx.mydomain.com>;tag=as4d223616 To: <sip:2383@DOWNSTREAM_IP:10533;transport=TLS> Call-ID: 1511475b69f6379b08f04d110f534da1(a)mypbx.mydomain.com CSeq: 102 CANCEL User-Agent: B2B Content-Length: 0 T 2020/05/26 08:40:10.941899 KAMAILIO_IP:5061 -> DOWNSTREAM_IP:10533 CANCEL sip:2383@DOWNSTREAM_IP:10533;transport=TLS SIP/2.0 Via: SIP/2.0/TLS proxy.mydomain.com:5061;branch=z9hG4bK3cbb.4c6a526982fa1fb93ca1ba73afd8484f.0 Max-Forwards: 69 From: "some callerid" <sip:18001111111@mypbx.mydomain.com>;tag=as4d223616 To: <sip:2383@DOWNSTREAM_IP:10533;transport=TLS> Call-ID: 1511475b69f6379b08f04d110f534da1(a)mypbx.mydomain.com CSeq: 102 CANCEL Content-Length: 0 U 2020/05/26 08:40:10.941935 KAMAILIO_IP:5060 -> PBXIP:5060 SIP/2.0 200 canceling Via: SIP/2.0/UDP PBXIP:5060;branch=z9hG4bK450689d6 From: "some callerid" <sip:18001111111@mypbx.mydomain.com>;tag=as4d223616 To: <sip:2383@DOWNSTREAM_IP :10533;transport=TLS>;tag=a6a1c5f60faecf035a1ae5b6e96e979a-612a1516 Call-ID: 1511475b69f6379b08f04d110f534da1(a)mypbx.mydomain.com CSeq: 102 CANCEL Server: Edge Content-Length: 0 Thanks, --Sergiu

2 2

Blind call forwarding with kamailio
by Son Vu 27 May '20

27 May '20

Dear all, I follow tutorial to setup blind call forwarding with kamailio but it seems outdate https://kamailio.org/docs/ser-getting-started/SER-GettingStarted.pdf https://www.kamailio.org/wiki/tutorials/mini-howto-admin/call_forwarding?s%… Is anyone setup it with kamailio? Could you please help me some guidance on this? Best regards, Son -- ================== Best regards, Son Vu

1 0

Using variables in remove_hf
by Cinthia Leung 27 May '20

27 May '20

Hi, I'm trying to use this textops function with a variable, ie remove_hf($var(x)). I know the documentation does not explicitly say that variables are allowed, but it works. However, it's throwing the following error: textops [textops.c:3431]: hname_fixup(): using hdr type name <P-Top-Secret> <core> [core/mem/q_malloc.c:487]: qm_free(): WARNING: free(0) called from core: core/action.c: do_action(1132) Is it something you'd highly recommend against doing? Thanks. Cindy

2 1

High load average
by Володимир Іванець 26 May '20

26 May '20

Hello everyone! I'm running Kamailio version 5.3.3 on a CentOS 6. I started noticing that "load average" value increases rapidly with the start of Kamailio: # uptime 17:47:52 up 4 days, 17:47, 3 users, load average: 7.02, 7.01, 6.02 It will start to decrease immediately after Kamailio is stopped. Does anyone know what could cause this and how to troubleshoot it? Thank you!

4 11

RTPEngine call associations
by Alex Balashov 25 May '20

25 May '20

Hi, When using a single RTPEngine, or a single RTPEngine per set, it is possible to make stateless RTPEngine calls from Kamailio, since everything is keyed by Call-ID + tag. In other words, one can set up a call, answer it, restart Kamailio, and still send a 'delete' to RTPEngine successfully because there's no runtime state that needs to persist within Kamailio. How does this work with multiple RTPEngines in a set? There is clearly some runtime state being kept to map (Call-ID, tag) => <RTPEngine instance within a set>, otherwise successive stream management commands would be round-robined within the set just like initial 'offer' commands. I take it this association is lost across Kamailio restarts, since I see no mechanism to persist it. Does that mean that subsequent stream management commands (e.g. updated 'offer' / 'answer' on re-INVITE, 'delete' on BYE) are blasted out to all instances within a set prophylactically? Or is the recipient instance chosen at random as it would be with an initial 'offer' command? Thanks, -- Alex -- Alex Balashov | Principal | Evariste Systems LLC Tel: +1-706-510-6800 / +1-800-250-5920 (toll-free) Web: http://www.evaristesys.com/, http://www.csrpswitch.com/

2 1

multi-IP Kamailio
by christian (cx labs) 25 May '20

25 May '20

Hi, you guys helped out so quickly with my last question, so here's me again, with another issue. I need Kamailio to be a sip proxy between two other SIP servers. All trunks are static IP only, no Auth. The specialty is that the Kamailio proxy needs to manipulate different fields in the SIP flow, depending on which public IP is used. So, for example: Provider A, IP 1 -----> Kamailio,* IP A* "change From Header" -----> Provider B, IP 2 Provider A, IP 1 -----> Kamailio, *IP B* "change Request URI" -----> Provider B, IP 2 So Kamailio needs to behave differently, depending on which IP receives (and also forwards!) the call. What do you think? Cheers, Chris.

3 2

488 Not Acceptable Here when remove codecs with rtcp-fb
by José Lopes 25 May '20

25 May '20

Hello, I am using KSR.sdpops.keep_codecs_by_name to remove some codecs. When I make a call between two webrtc clients and I keep only VP8 video codec, the browser rejects the call with 488 Not Acceptable Here. I notice the attributes a=rtcp-fb: of codecs removed are kept. I removed that attributes with: KSR.textops.replace_body_atonce("a=rtcp-fb:1.*\n", ""); KSR.textops.replace_body_atonce("a=rtcp-fb:98.*\n", ""); And the call worked. There is another way to remove these attributes related to codec name? Or is it possible to get the ids of the codecs with dynamic payload type numbers from SDP? Thanks for your attention. Best regards, Jose Lopes

5 10

IPV6 header in IPV4 : ACK for 200 OK never received
by Abdoul Osséni 25 May '20

25 May '20

Hello, I encounter unexpected behavior. The call flow is: UAC --> Kamailio + Rtpengine (full ipv4) --> Asterisk (full IPV4). I configured the uac to use IPV4 but the SIP INVITES include IPV6 in headers and sdp messages. I think this is a bug on the UAC side. Example: 1. UAC INVITE *INVITE* sip:xxx@sip.xxx.com SIP/2.0 Via: SIP/2.0/TLS [2a04:cec0:101a:ebe2:9949:d81c:23a6:ae10]:42580;branch=*z9hG4bK.I8U1RQDbW* ;rport ..... ..... v=0 o=59130c8f7268a_2 2922 1557 IN IP6 2a04:cec0:101a:ebe2:9949:d81c:23a6:ae10 s=Talk c=IN IP6 2a04:cec0:101a:ebe2:9949:d81c:23a6:ae10 2. We sent 200 OK response like : Via: SIP/2.0/TLS [2a04:cec0:101a:ebe2:9949:d81c:23a6:ae10]:42580;received=80.214.78.1;branch= *z9hG4bK.I8U1RQDbW*;rport=1364 v=0 o=x 887503534 887503534 IN IP4 51.159.2.164 s=x c=IN IP4 51.159.2.164 3. We never receive the ACK message. Is there a Kamailio or Rtpengine solution to solve the problem? Any help will be very appreciate. Abdoul OSSENI https://www.africallshop.com/

2 1

Teams 404
by sip user 25 May '20

25 May '20

Hi, when I try to call Kamailio -> Teams.. this one always respònd to me with a 404 Q.850 cause=1 (Unallocated number). Could be this problem from Teams? Has anyone ever encountered this problem? Thanks

1 0

Change ACK
by sip user 25 May '20

25 May '20

Hi, is it possible change ACK? I have connected Asterisk to Kamailio like extension, with auth and secret. I can redirect tha call from kamailio to my Asterisk but always in ACK, in Asterisk, I received s@..... and in TO number@... is it possible send in ACk the dialed number, like in TO?? Thanks

2 2

sip forward with manipulations
by christian (cx labs) 25 May '20

25 May '20

Hi, I'm trying to set up a Kamailio server as a proxy to forward SIP messages between two trunks, where I manipulate only the INVITE in the following manner: incoming call: INVITE +4969123456@server1 outgoing call: round robin to INVITE user@4969123456-sip-server2a INVITE user@4969123456-sip-server2b INVITE user@4969123456-sip-server2c Can anyone point me in the right direction? Both SIP trunks are IP registration only. Also, I am aware how strange this is, but the upstream service has decided to interpret SIP in a novel way. Cheers, Chris

4 3

No audio when calling from SIP to WEBRTC
by Chirag Desai 22 May '20

22 May '20

Hi all, I have configured Kamailio for WebSockets following this guide as an example: https://github.com/havfo/WEBRTC-to-SIP/blob/master/etc/kamailio/kamailio.cfg With sip.js and jssip I'm able to initiate a call from WebRTC to SIP and establish a call successfully. The issue arises when I try to receive a call from a SIP device. In this case the call establishes but there is no audio in either direction. I *think* the issue is with RTP Engine and I've raised a bug there, but I'm not sure why it is misbehaving https://github.com/sipwise/rtpengine/issues/983. There are some logs from RTP engine posted here. The sip device communicates with Kamailio over UDP / RTP, nothing is encrypted. I would appreciate any guidance. Thanks in advance, C

3 16

PRACK support in RTPENGINE module?
by Chris Martineau 22 May '20

22 May '20

Hi, Trying to use early media with prack support and rtpengine without success. INVITE(no SDP)--> 183(SDP)<-- PRACK(SDP)--> 200ok(no SDP)<-- ACK(no SDP)--> Looking at the code it does not seem to allow for PRACK in the methods it supports. In the rtpengine_manage code... if (!(method==METHOD_INVITE || method==METHOD_ACK || method==METHOD_CANCEL || method==METHOD_BYE || method==METHOD_UPDATE)) return -1; In the rtpengine_answer code... rtpengine_answer1_f(struct sip_msg *msg, char *str1, char *str2) { if (msg->first_line.type == SIP_REQUEST) if (msg->first_line.u.request.method_value != METHOD_ACK) return -1; return rtpengine_rtpp_set_wrap_fparam(msg, rtpengine_answer_wrap, str1, 2, OP_ANSWER); } Returns -1 no matter what function you use. I have checked code all the way up to 5.3 and on the master. I may be looking at this wrong and it is supported, but it doesn't look like it. Could you please advise if this is a oversight or if there is a specific reason it is not included. Thanks Chris Chris Martineau | Senior Telephony Engineer [vid] See how Semafone works<https://www.semafone.com/our-solution/how-it-works/demo/> [b] Read our insightful blog<https://semafone.com/media-centre/blog/> [p] Switchboard: 0845 543 0822 / Mobile: [e] Chris.Martineau(a)semafone.com<mailto:Chris.Martineau@semafone.com> [w] Web: semafone.com<https://semafone.com> / Twitter: @semafone<https://twitter.com/semafone> / LinkedIn: Semafone<https://uk.linkedin.com/company/semafone> [a] SEMAFONE, Pannell House, Park Street, Guildford, Surrey GU1 4HN [Semafone]<https://www.semafone.com> Disclaimer Semafone is a limited company, registered in England, with registered number 06963868. Our registered office is at Pannell House, Park Street, Guildford, Surrey, GU1 4HN, United Kingdom. The information contained in this communication from the sender is confidential. It is intended solely for use by the recipient and others authorized to receive it. If you are not the recipient, you are hereby notified that any disclosure, copying, distribution or taking action in relation of the contents of this information is strictly prohibited and may be unlawful. If you have received this email in error, please send it back to us, and immediately and permanently delete it. This email has been scanned for viruses and malware, and may have been automatically archived by Mimecast Ltd, an innovator in Software as a Service (SaaS) for business. Providing a safer and more useful place for your human generated data. Specializing in; Security, archiving and compliance. To find out more Click Here (https://protect-eu.mimecast.com/s/6sS0CE8WmI1xZECyHvaY?domain=linkprotect.c…) . Although Semafone has taken reasonable precautions to ensure no viruses are present in this email, Semafone cannot accept responsibility for any loss or damage sustained as a result of computer viruses and the recipient must ensure that the email (and any attachments) are virus free. For information about how we process data and monitor communications please see our Privacy Policy (https://semafone.com/gb/privacy/)

3 9

RFC3261 transaction matching failed and get_record(): lookup failed
by Chris Atta 22 May '20

22 May '20

failed when try to #add or #invite a user to a private chat room with imc module. #join to a public room works! attached kamailio log: May 20 20:43:19 dev /usr/sbin/kamailio[23771]: DEBUG: <core> [core/tcp_read.c:198]: tcp_emit_closed_event(): TCP closed event creation triggered (reason: 0) May 20 20:43:19 dev /usr/sbin/kamailio[23771]: DEBUG: <core> [core/tcp_read.c:206]: tcp_emit_closed_event(): no callback registering for handling TCP closed event May 20 20:43:19 dev /usr/sbin/kamailio[23771]: DEBUG: <core> [core/tcp_read.c:315]: tcp_read_data(): EOF on 0x7fbdf2a25f28, FD 11 ([37.77.127.147]:36732 -> May 20 20:43:19 dev /usr/sbin/kamailio[23771]: DEBUG: <core> [core/tcp_read.c:316]: tcp_read_data(): -> [195.201.132.99]:5061) May 20 20:43:19 dev /usr/sbin/kamailio[23771]: DEBUG: <core> [core/tcp_read.c:1507]: tcp_read_req(): EOF May 20 20:43:19 dev /usr/sbin/kamailio[23771]: DEBUG: <core> [core/tcp_read.c:1664]: release_tcpconn(): releasing con 0x7fbdf2a25f28, state -1, fd=11, id=5 ([37.77.127.147]:36732 -> [37.77.127.147]:5061) May 20 20:43:19 dev /usr/sbin/kamailio[23771]: DEBUG: <core> [core/tcp_read.c:1665]: release_tcpconn(): extra_data 0x7fbdf2a409a0 May 20 20:43:19 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3308]: handle_tcp_child(): reader response= 7fbdf2a25f28, -1 from 6 May 20 20:43:19 dev /usr/sbin/kamailio[23773]: DEBUG: tls [tls_server.c:667]: tls_h_close(): Closing SSL connection 0x7fbdf2a409a0 May 20 20:43:22 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560299 May 20 20:43:27 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560304 May 20 20:43:27 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1274]: m_clean_silo(): cleaning expired messages May 20 20:43:32 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560309 May 20 20:43:33 dev /usr/sbin/kamailio[23736]: DEBUG: tm [t_reply.c:1263]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=408 May 20 20:43:33 dev /usr/sbin/kamailio[23736]: DEBUG: tm [t_reply.c:2074]: local_reply(): branch=0, save=0, winner=0 May 20 20:43:33 dev /usr/sbin/kamailio[23736]: DEBUG: tm [t_reply.c:2113]: local_reply(): local transaction completed 408/0 (totag retr: 0/0) May 20 20:43:37 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560314 May 20 20:43:42 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/ip_addr.c:229]: print_ip(): tcpconn_new: new tcp connection: 37.77.127.147 May 20 20:43:42 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:999]: tcpconn_new(): on port 35906, type 3 May 20 20:43:42 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:1309]: tcpconn_add(): hashes: 1993:1592:1498, 6 May 20 20:43:42 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa5bf80, 49, 2, 0x7fbdf2a25f28), fd_no=38 May 20 20:43:42 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/io_wait.h:602]: io_watch_del(): DBG: io_watch_del (0xa5bf80, 49, -1, 0x0) fd_no=39 called May 20 20:43:42 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:4196]: handle_tcpconn_ev(): sending to child, events 1 May 20 20:43:42 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3878]: send2child(): selected tcp worker 7 28(23772) for activity on [tls:0.0.0.0:5061], 0x7fbdf2a25f28 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_read.c:1740]: handle_io(): received n=8 con=0x7fbdf2a25f28, fd=11 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: tls [tls_server.c:199]: tls_complete_init(): completing tls connection initialization May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: tls [tls_server.c:228]: tls_complete_init(): Using initial TLS domain TLSs<default> (dom 0x7fbdf283eb58 ctx 0x7fbdf28a1e20 sn []) May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: tls [tls_domain.c:724]: sr_ssl_ctx_info_callback(): SSL handshake started May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_main.c:2460]: tcpconn_do_send(): sending... May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_main.c:2494]: tcpconn_do_send(): after real write: c= 0x7fbdf2a25f28 n=2974 fd=11 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_main.c:2495]: tcpconn_do_send(): buf=#012#026#003#003 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xaa5680, 11, 2, 0x7fbdf2a25f28), fd_no=1 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: tls [tls_domain.c:736]: sr_ssl_ctx_info_callback(): SSL handshake done May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: tls [tls_domain.c:740]: sr_ssl_ctx_info_callback(): SSL disable renegotiation May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: tls [tls_server.c:415]: tls_accept(): TLS accept successful May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: tls [tls_server.c:422]: tls_accept(): tls_accept: new connection from 37.77.127.147:35906 using TLSv1/SSLv3 ECDHE-RSA-AES256-GCM-SHA384 256 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: tls [tls_server.c:425]: tls_accept(): tls_accept: local socket: 195.201.132.99:5061 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: tls [tls_server.c:436]: tls_accept(): tls_accept: client did not present a certificate May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_main.c:2460]: tcpconn_do_send(): sending... May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_main.c:2494]: tcpconn_do_send(): after real write: c= 0x7fbdf2a25f28 n=258 fd=11 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_main.c:2495]: tcpconn_do_send(): buf=#012#026#003#003 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_read.c:1540]: tcp_read_req(): content-length=264 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <MESSAGE> May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: < sips:room002@dev.pynlab.com;transport=TLS> May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPj.Z.jKY.-rqjbw05tczxvsl1hqgmPWSDj>; state=6 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [31]; uri=[ sips:room002@dev.pynlab.com] May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [< sips:room002@dev.pynlab.com>#015#012] May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <49520> <MESSAGE> May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/receive.c:205]: receive_msg(): --- received sip message - request - call-id: [3KayGmm0.QsAWphKP57ypGB8BIOG9xsx] - cseq: [49520 MESSAGE] May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/receive.c:248]: receive_msg(): preparing to run routing scripts... May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=4 a=5 n=route May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=138 a=16 n=if May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=135 a=25 n=is_method May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=136 a=2 n=return May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=7 a=5 n=route May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=183 a=16 n=if May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==9 && [37.77.127.147] == [127.0.0.1] May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==9 && [37.77.127.147] == [127.0.0.1] May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==7 && [37.77.127.147] == [0.0.0.0] May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/socket_info.c:583]: grep_sock_info(): checking advertise if host==us: 13==14 && [37.77.127.147] == [195.201.132.99] May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/forward.c:412]: check_self(): host != me May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=171 a=25 n=is_method May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=190 a=16 n=if May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=195 a=16 n=if May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=190 a=25 n=mf_process_maxfwd_header May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=203 a=16 n=if May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=195 a=25 n=is_method May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=208 a=16 n=if May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=203 a=26 n=sanity_check May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=264 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=Y6PtMn4f5iWSYw672lqeTu8AsprDvBS8 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1 May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=213 a=16 n=if May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=208 a=25 n=is_method May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=212 a=16 n=if May 20 20:43:42 dev /usr/sbin/kamailio[23772]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=210 a=2 n=exit May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/receive.c:289]: receive_msg(): request-route executed in: 613 usec May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) May 20 20:43:42 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/receive.c:378]: receive_msg(): cleaning up May 20 20:43:42 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560319 May 20 20:43:47 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560324 May 20 20:43:48 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/io_wait.h:602]: io_watch_del(): DBG: io_watch_del (0xaa5680, 11, -1, 0x10) fd_no=2 called May 20 20:43:48 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_read.c:1664]: release_tcpconn(): releasing con 0x7fbdf2a25f28, state 1, fd=11, id=6 ([37.77.127.147]:35906 -> [37.77.127.147]:5061) May 20 20:43:48 dev /usr/sbin/kamailio[23772]: DEBUG: <core> [core/tcp_read.c:1665]: release_tcpconn(): extra_data 0x7fbdf2a409a0 May 20 20:43:48 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3308]: handle_tcp_child(): reader response= 7fbdf2a25f28, 1 from 7 May 20 20:43:48 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa5bf80, 49, 2, 0x7fbdf2a25f28), fd_no=38 May 20 20:43:48 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3435]: handle_tcp_child(): CONN_RELEASE 0x7fbdf2a25f28 refcnt= 1 May 20 20:43:52 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/io_wait.h:602]: io_watch_del(): DBG: io_watch_del (0xa5bf80, 49, -1, 0x0) fd_no=39 called May 20 20:43:52 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:4196]: handle_tcpconn_ev(): sending to child, events 1 May 20 20:43:52 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3878]: send2child(): selected tcp worker 0 21(23765) for activity on [tls:0.0.0.0:5061], 0x7fbdf2a25f28 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/tcp_read.c:1740]: handle_io(): received n=8 con=0x7fbdf2a25f28, fd=11 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/tcp_read.c:1540]: tcp_read_req(): content-length=25 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <MESSAGE> May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: < sips:room002@dev.pynlab.com;transport=TLS> May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjH-sqvDaQtX52oig1Z9xMlYueNGFkJ4xm>; state=6 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [31]; uri=[ sips:room002@dev.pynlab.com] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [< sips:room002@dev.pynlab.com>#015#012] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <8712> <MESSAGE> May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/receive.c:205]: receive_msg(): --- received sip message - request - call-id: [s32551vkfpHNKwAdUPlt8w9smFgAAb3t] - cseq: [8712 MESSAGE] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/receive.c:248]: receive_msg(): preparing to run routing scripts... May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=4 a=5 n=route May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=138 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=135 a=25 n=is_method May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=136 a=2 n=return May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=7 a=5 n=route May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=183 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==9 && [37.77.127.147] == [127.0.0.1] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==9 && [37.77.127.147] == [127.0.0.1] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==7 && [37.77.127.147] == [0.0.0.0] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/socket_info.c:583]: grep_sock_info(): checking advertise if host==us: 13==14 && [37.77.127.147] == [195.201.132.99] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/forward.c:412]: check_self(): host != me May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=171 a=25 n=is_method May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=190 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=195 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=190 a=25 n=mf_process_maxfwd_header May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=203 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=195 a=25 n=is_method May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=208 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=203 a=26 n=sanity_check May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=25 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=NXDpIWMgNBoIc34osnQ-PPAFaaRGD8Se May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=213 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=208 a=25 n=is_method May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=212 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=10 a=5 n=route May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=539 a=55 n=force_rport May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=555 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=551 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=545 a=25 n=is_method May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=551 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=548 a=24 n=is_first_hop May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: siputils [sipops.c:159]: is_first_hop(): no 2nd via found - first hop May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=549 a=24 n=set_contact_alias May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=552 a=39 n=setflag May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=555 a=2 n=return May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=23 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=13 a=25 n=is_method May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=23 a=5 n=route May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio_routes.cfg] l=229 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio_routes.cfg] l=219 a=24 n=has_totag May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: siputils [checks.c:120]: has_totag(): no totag May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio_routes.cfg] l=219 a=2 n=return May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=33 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=29 a=24 n=t_precheck_trans May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=33 a=24 n=t_check_trans May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: tm [t_lookup.c:1019]: t_check_msg(): msg (0x7fbdfdd20280) id=2 global id=0 T start=0xffffffffffffffff May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: tm [t_lookup.c:477]: t_lookup_request(): start searching: hash=56282, isACK=0 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: tm [t_lookup.c:435]: matching_3261(): RFC3261 transaction matching failed May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: tm [t_lookup.c:659]: t_lookup_request(): no transaction found May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: tm [t_lookup.c:1088]: t_check_msg(): msg (0x7fbdfdd20280) id=2 global id=2 T end=(nil) May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=48 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=41 a=25 n=is_method May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=71 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=51 a=25 n=xdbg May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <script>: script: message from [sip:651097007@dev.pynlab.com] r-uri [ sips:room002@dev.pynlab.com;transport=TLS] msg [#invite 704021003 room002] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=61 a=16 n=if May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=52 a=25 n=is_method May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=54 a=3 n=log May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=55 a=26 n=sl_send_reply May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: sl [sl_funcs.c:490]: sl_run_callbacks(): execute callback for event type 1 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: siptrace [siptrace.c:1350]: trace_sl_onreply_out(): trace off... May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/tcp_main.c:2226]: tcpconn_send_put(): send from reader (23765 (21)), reusing fd May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/tcp_main.c:2460]: tcpconn_do_send(): sending... May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/tcp_main.c:2494]: tcpconn_do_send(): after real write: c= 0x7fbdf2a25f28 n=433 fd=11 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/tcp_main.c:2495]: tcpconn_do_send(): buf=#012#027#003#003#001¬·ÝˆV#037£G—Üˆˆ â¿S½²#033ðPñÎ_e.¹¯„Ív Œ92‚Âës#003˜…ž§ ü#012æTÓô³YêLzOeeb%èÏewÂ#037Ì2ˆ May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=56 a=24 n=imc_manager May 20 20:43:52 dev /usr/sbin/kamailio[23765]: ERROR: imc [imc.c:499]: ki_imc_manager(): BODY #invite 704021003 room002 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: imc [imc.c:502]: ki_imc_manager(): found command May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: imc [imc_cmd.c:144]: imc_parse_cmd(): command: [invite] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: imc [imc_cmd.c:149]: imc_parse_cmd(): parameter 0=[704021003] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: imc [imc_cmd.c:149]: imc_parse_cmd(): parameter 1=[room002] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: imc [imc_mng.c:371]: imc_get_member(): found member May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: imc [imc_mng.c:320]: imc_add_member(): [uri]= sip:704021003@dev.pynlab.com May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: imc [imc_mng.c:324]: imc_add_member(): [user]= 704021003 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: imc [imc_cmd.c:484]: imc_handle_invite(): to=[sip:704021003@dev.pynlab.com]#012from=[ sip:room002@dev.pynlab.com]#012body=[INVITE from: 704021003(a)dev.pynlab.com(Type: '#accept' or '#deny')] May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: tm [uac.c:427]: t_uac_prepare(): next_hop=<sip:704021003@dev.pynlab.com> May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/dns_cache.c:542]: _dns_hash_find(): (_sip._udp.dev.pynlab.com(24), 33), h=5 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/resolve.c:744]: get_record(): lookup(_sip._udp.dev.pynlab.com, 33) failed May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/dns_cache.c:871]: dns_cache_mk_bad_entry(): (_sip._udp.dev.pynlab.com, 33, 60, 1) May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/dns_cache.c:805]: dns_cache_add(): adding _sip._udp.dev.pynlab.com(24) 33 (flags=1) at 5 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/dns_cache.c:542]: _dns_hash_find(): (dev.pynlab.com(14), 1), h=696 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: tm [uac.c:152]: dlg2hash(): hashid 5690 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: tm [uac.c:646]: send_prepared_request_impl(): uac: 0x7fbdf2a6c238 branch: 0 to 195.201.132.99:5060 May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring May 20 20:43:52 dev /usr/sbin/kamailio[23765]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=57 a=2 n=exit May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/receive.c:289]: receive_msg(): request-route executed in: 11030 usec May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/receive.c:378]: receive_msg(): cleaning up May 20 20:43:52 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xaa5680, 11, 2, 0x7fbdf2a25f28), fd_no=1 May 20 20:43:52 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560329 May 20 20:43:57 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560334 May 20 20:43:58 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/io_wait.h:602]: io_watch_del(): DBG: io_watch_del (0xaa5680, 11, -1, 0x10) fd_no=2 called May 20 20:43:58 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/tcp_read.c:1664]: release_tcpconn(): releasing con 0x7fbdf2a25f28, state 1, fd=11, id=6 ([37.77.127.147]:35906 -> [37.77.127.147]:5061) May 20 20:43:58 dev /usr/sbin/kamailio[23765]: DEBUG: <core> [core/tcp_read.c:1665]: release_tcpconn(): extra_data 0x7fbdf2a409a0 May 20 20:43:58 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3308]: handle_tcp_child(): reader response= 7fbdf2a25f28, 1 from 0 May 20 20:43:58 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa5bf80, 49, 2, 0x7fbdf2a25f28), fd_no=38 May 20 20:43:58 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3435]: handle_tcp_child(): CONN_RELEASE 0x7fbdf2a25f28 refcnt= 1 May 20 20:44:02 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/ip_addr.c:229]: print_ip(): tcpconn_new: new tcp connection: 37.77.127.147 May 20 20:44:02 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:999]: tcpconn_new(): on port 36289, type 3 May 20 20:44:02 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:1309]: tcpconn_add(): hashes: 1609:1976:1114, 7 May 20 20:44:02 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa5bf80, 50, 2, 0x7fbdf2a7c0b0), fd_no=39 May 20 20:44:02 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/io_wait.h:602]: io_watch_del(): DBG: io_watch_del (0xa5bf80, 50, -1, 0x0) fd_no=40 called May 20 20:44:02 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:4196]: handle_tcpconn_ev(): sending to child, events 1 May 20 20:44:02 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3878]: send2child(): selected tcp worker 1 22(23766) for activity on [tls:0.0.0.0:5061], 0x7fbdf2a7c0b0 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_read.c:1740]: handle_io(): received n=8 con=0x7fbdf2a7c0b0, fd=11 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: tls [tls_server.c:199]: tls_complete_init(): completing tls connection initialization May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: tls [tls_server.c:228]: tls_complete_init(): Using initial TLS domain TLSs<default> (dom 0x7fbdf283eb58 ctx 0x7fbdf28974a0 sn []) May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: tls [tls_domain.c:724]: sr_ssl_ctx_info_callback(): SSL handshake started May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_main.c:2460]: tcpconn_do_send(): sending... May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_main.c:2494]: tcpconn_do_send(): after real write: c= 0x7fbdf2a7c0b0 n=2974 fd=11 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_main.c:2495]: tcpconn_do_send(): buf=#012#026#003#003 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xaa5680, 11, 2, 0x7fbdf2a7c0b0), fd_no=1 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: tls [tls_domain.c:736]: sr_ssl_ctx_info_callback(): SSL handshake done May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: tls [tls_domain.c:740]: sr_ssl_ctx_info_callback(): SSL disable renegotiation May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: tls [tls_server.c:415]: tls_accept(): TLS accept successful May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: tls [tls_server.c:422]: tls_accept(): tls_accept: new connection from 37.77.127.147:36289 using TLSv1/SSLv3 ECDHE-RSA-AES256-GCM-SHA384 256 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: tls [tls_server.c:425]: tls_accept(): tls_accept: local socket: 195.201.132.99:5061 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: tls [tls_server.c:436]: tls_accept(): tls_accept: client did not present a certificate May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_main.c:2460]: tcpconn_do_send(): sending... May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_main.c:2494]: tcpconn_do_send(): after real write: c= 0x7fbdf2a7c0b0 n=258 fd=11 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_main.c:2495]: tcpconn_do_send(): buf=#012#026#003#003 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_read.c:1540]: tcp_read_req(): content-length=264 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <MESSAGE> May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: < sips:room002@dev.pynlab.com;transport=TLS> May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjb53tgvUypUfxAVP3JE33IG0Mti7LxdJf>; state=6 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [31]; uri=[ sips:room002@dev.pynlab.com] May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [< sips:room002@dev.pynlab.com>#015#012] May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <21738> <MESSAGE> May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/receive.c:205]: receive_msg(): --- received sip message - request - call-id: [UtD.tzGUrqp658sgiCsswG5XkExXahlZ] - cseq: [21738 MESSAGE] May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/receive.c:248]: receive_msg(): preparing to run routing scripts... May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=4 a=5 n=route May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=138 a=16 n=if May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=135 a=25 n=is_method May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=136 a=2 n=return May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=7 a=5 n=route May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=183 a=16 n=if May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==9 && [37.77.127.147] == [127.0.0.1] May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==9 && [37.77.127.147] == [127.0.0.1] May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==7 && [37.77.127.147] == [0.0.0.0] May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/socket_info.c:583]: grep_sock_info(): checking advertise if host==us: 13==14 && [37.77.127.147] == [195.201.132.99] May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/forward.c:412]: check_self(): host != me May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=171 a=25 n=is_method May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=190 a=16 n=if May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=195 a=16 n=if May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=190 a=25 n=mf_process_maxfwd_header May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=203 a=16 n=if May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=195 a=25 n=is_method May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=208 a=16 n=if May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=203 a=26 n=sanity_check May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=264 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=YxAZ238hjrNKzwbGOxUxPQW2SIO8LMIn May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1 May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=213 a=16 n=if May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=208 a=25 n=is_method May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=212 a=16 n=if May 20 20:44:02 dev /usr/sbin/kamailio[23766]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=210 a=2 n=exit May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/receive.c:289]: receive_msg(): request-route executed in: 704 usec May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) May 20 20:44:02 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/receive.c:378]: receive_msg(): cleaning up May 20 20:44:02 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560339 May 20 20:44:07 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560344 May 20 20:44:08 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/io_wait.h:602]: io_watch_del(): DBG: io_watch_del (0xaa5680, 11, -1, 0x10) fd_no=2 called May 20 20:44:08 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_read.c:1664]: release_tcpconn(): releasing con 0x7fbdf2a7c0b0, state 1, fd=11, id=7 ([37.77.127.147]:36289 -> [37.77.127.147]:5061) May 20 20:44:08 dev /usr/sbin/kamailio[23766]: DEBUG: <core> [core/tcp_read.c:1665]: release_tcpconn(): extra_data 0x7fbdf2a2db18 May 20 20:44:08 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3308]: handle_tcp_child(): reader response= 7fbdf2a7c0b0, 1 from 1 May 20 20:44:08 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa5bf80, 50, 2, 0x7fbdf2a7c0b0), fd_no=39 May 20 20:44:08 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3435]: handle_tcp_child(): CONN_RELEASE 0x7fbdf2a7c0b0 refcnt= 1 May 20 20:44:09 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/io_wait.h:602]: io_watch_del(): DBG: io_watch_del (0xa5bf80, 50, -1, 0x0) fd_no=40 called May 20 20:44:09 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:4196]: handle_tcpconn_ev(): sending to child, events 1 May 20 20:44:09 dev /usr/sbin/kamailio[23773]: DEBUG: <core> [core/tcp_main.c:3878]: send2child(): selected tcp worker 2 23(23767) for activity on [tls:0.0.0.0:5061], 0x7fbdf2a7c0b0 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/tcp_read.c:1740]: handle_io(): received n=8 con=0x7fbdf2a7c0b0, fd=11 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/tcp_read.c:1540]: tcp_read_req(): content-length=15 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <MESSAGE> May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: < sips:room002@dev.pynlab.com;transport=TLS> May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPjqh8tjTMEwA1J8Q0Po93DllWCft4Hz-WA>; state=6 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, <alias> = <n/a>; state=16 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [31]; uri=[ sips:room002@dev.pynlab.com] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [< sips:room002@dev.pynlab.com>#015#012] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <29567> <MESSAGE> May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/receive.c:205]: receive_msg(): --- received sip message - request - call-id: [8UDGmawPuNWXuk8iDNJuE9ekNCiH1mVN] - cseq: [29567 MESSAGE] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/receive.c:248]: receive_msg(): preparing to run routing scripts... May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=4 a=5 n=route May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=138 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=135 a=25 n=is_method May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[SIP_TRACE_LOG] c=[/etc/kamailio/kamailio_routes.cfg] l=136 a=2 n=return May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=7 a=5 n=route May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=183 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==9 && [37.77.127.147] == [127.0.0.1] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==9 && [37.77.127.147] == [127.0.0.1] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/socket_info.c:564]: grep_sock_info(): checking if host==us: 13==7 && [37.77.127.147] == [0.0.0.0] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/socket_info.c:583]: grep_sock_info(): checking advertise if host==us: 13==14 && [37.77.127.147] == [195.201.132.99] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/forward.c:412]: check_self(): host != me May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=171 a=25 n=is_method May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=190 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=195 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=190 a=25 n=mf_process_maxfwd_header May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=203 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=195 a=25 n=is_method May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=208 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=203 a=26 n=sanity_check May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=15 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=I5suPLl6lYTIqikf8qYpr6QWNEqeaR30 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=213 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=208 a=25 n=is_method May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio_routes.cfg] l=212 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=10 a=5 n=route May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=539 a=55 n=force_rport May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=555 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=551 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=545 a=25 n=is_method May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=551 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=548 a=24 n=is_first_hop May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: siputils [sipops.c:159]: is_first_hop(): no 2nd via found - first hop May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=549 a=24 n=set_contact_alias May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=552 a=39 n=setflag May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio_routes.cfg] l=555 a=2 n=return May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=23 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=13 a=25 n=is_method May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=23 a=5 n=route May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio_routes.cfg] l=229 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio_routes.cfg] l=219 a=24 n=has_totag May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: siputils [checks.c:120]: has_totag(): no totag May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio_routes.cfg] l=219 a=2 n=return May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=33 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=29 a=24 n=t_precheck_trans May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=33 a=24 n=t_check_trans May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [t_lookup.c:1019]: t_check_msg(): msg (0x7fbdfdd20280) id=2 global id=1 T start=(nil) May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [t_lookup.c:477]: t_lookup_request(): start searching: hash=1234, isACK=0 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [t_lookup.c:435]: matching_3261(): RFC3261 transaction matching failed May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [t_lookup.c:659]: t_lookup_request(): no transaction found May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [t_lookup.c:1088]: t_check_msg(): msg (0x7fbdfdd20280) id=2 global id=2 T end=(nil) May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=48 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=41 a=25 n=is_method May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=71 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=51 a=25 n=xdbg May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <script>: script: message from [sip:704021003@dev.pynlab.com] r-uri [ sips:room002@dev.pynlab.com;transport=TLS] msg [#accept room002] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=61 a=16 n=if May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=52 a=25 n=is_method May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=54 a=3 n=log May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=55 a=26 n=sl_send_reply May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: sl [sl_funcs.c:490]: sl_run_callbacks(): execute callback for event type 1 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: siptrace [siptrace.c:1350]: trace_sl_onreply_out(): trace off... May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/tcp_main.c:2226]: tcpconn_send_put(): send from reader (23767 (23)), reusing fd May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/tcp_main.c:2460]: tcpconn_do_send(): sending... May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/tcp_main.c:2494]: tcpconn_do_send(): after real write: c= 0x7fbdf2a7c0b0 n=434 fd=11 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/tcp_main.c:2495]: tcpconn_do_send(): buf=#012#027#003#003#001aÖ*~‘ÖŒÁ*~Ì#035R9à‹#034¦ÆFîí¯#015sÖvy¯]ß÷zdï}sT ÑSê{#016^±þKX`#001Öo˜:#013™æØD#005pÓ!3U¨ÛÞ]®#027…*ž)Ûøï>žÆ_=Y3#022$+Hyj6Îc#035#027#016#033ÿŒ#024#012#030¢#036¨±LÚE#002Ó öe#030VÉ›#015 —‹VòV2žJÙ)°›^“#013ç#005— ‡¸þ›Â#013$2î›ƒª#001Ù«-rAe ;ö-DY(#026 -Ÿú@@5‚#025è.9®‹Àb¯³qãš.bn <http://xn--q-yfa29a.bn>»v¿²ý,q¶*P—µæèÒšÔb(ÚhòŠv#026FòŠ;fý]#024ƒI#022…ÞütàZrÈ$êln¹ç z”Ç©TÜ‘#015Bƒî£Ñ+vÝÚíûñ#012#005#032[FÐëè¥#024»%_™‹$×½˜3Ú%ÀŒJ#034<©9í{ø#031h•#037"°qß³…–¨óùºñ#012ì1§úPàB‡10veÓö=”Ñ[‹¡Ì‘1#037à#033>±%ƒÖ\Ivœø«úP May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=56 a=24 n=imc_manager May 20 20:44:09 dev /usr/sbin/kamailio[23767]: ERROR: imc [imc.c:499]: ki_imc_manager(): BODY #accept room002 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: imc [imc.c:502]: ki_imc_manager(): found command May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: imc [imc_cmd.c:144]: imc_parse_cmd(): command: [accept] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: imc [imc_cmd.c:149]: imc_parse_cmd(): parameter 0=[room002] May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: imc [imc_mng.c:371]: imc_get_member(): found member May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: imc [imc_cmd.c:1097]: imc_room_broadcast(): nr = 2 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: imc [imc_cmd.c:1101]: imc_room_broadcast(): to uri = sip:651097007@dev.pynlab.com May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [uac.c:427]: t_uac_prepare(): next_hop=<sip:651097007@dev.pynlab.com> May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/dns_cache.c:542]: _dns_hash_find(): (_sip._udp.dev.pynlab.com(24), 33), h=5 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/dns_cache.c:542]: _dns_hash_find(): (dev.pynlab.com(14), 1), h=696 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [uac.c:152]: dlg2hash(): hashid 54753 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [uac.c:646]: send_prepared_request_impl(): uac: 0x7fbdf2a9ad58 branch: 0 to 195.201.132.99:5060 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: imc [imc_cmd.c:1101]: imc_room_broadcast(): to uri = sip:704021003@dev.pynlab.com May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [uac.c:427]: t_uac_prepare(): next_hop=<sip:704021003@dev.pynlab.com> May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/dns_cache.c:542]: _dns_hash_find(): (_sip._udp.dev.pynlab.com(24), 33), h=5 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/dns_cache.c:542]: _dns_hash_find(): (dev.pynlab.com(14), 1), h=696 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [uac.c:152]: dlg2hash(): hashid 54767 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [uac.c:646]: send_prepared_request_impl(): uac: 0x7fbdf2a9c408 branch: 0 to 195.201.132.99:5060 May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring May 20 20:44:09 dev /usr/sbin/kamailio[23767]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio_routes.cfg] l=57 a=2 n=exit May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/receive.c:289]: receive_msg(): request-route executed in: 3448 usec May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/receive.c:378]: receive_msg(): cleaning up May 20 20:44:09 dev /usr/sbin/kamailio[23767]: DEBUG: <core> [core/io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xaa5680, 11, 2, 0x7fbdf2a7c0b0), fd_no=1 May 20 20:44:12 dev /usr/sbin/kamailio[23736]: DEBUG: msilo [msilo.c:1214]: m_clean_silo(): cleaning stored messages - 51560349

1 0

Kamailio crashing several times a day
by Jacob Greene 21 May '20

21 May '20

Hello everyone! I'm having an issue and was wondering if anyone could point me in the right direction. I have a kamailio server that is crashing several times a day. I see the following message in the logs: > > May 20 21:00:02 ip-10-192-11-197 /usr/sbin/kamailio[14705]: CRITICAL: > <core> [core/pass_fd.c:277]: receive_fd(): EOF on 19 Then almost instantly the service restarts: > May 20 21:00:02 ip-10-192-11-197 /usr/sbin/kamailio[14687]: ALERT: <core> > [main.c:766]: handle_sigs(): child process 14689 exited by a signal 6 May 20 21:00:02 ip-10-192-11-197 /usr/sbin/kamailio[14687]: ALERT: <core> > [main.c:769]: handle_sigs(): core was generated > May 20 21:00:02 ip-10-192-11-197 /usr/sbin/kamailio[14687]: INFO: <core> > [main.c:792]: handle_sigs(): terminating due to SIGCHLD > May 20 21:00:02 ip-10-192-11-197 /usr/sbin/kamailio[14705]: INFO: <core> > [main.c:847]: sig_usr(): signal 15 received This is a dump of the log when this happens: https://pastebin.com/KAT9n0x9 The core dump doesn't make much sense to me, but this is it: https://pastebin.com/9rVZjsEn Any ideas on what may be causing this? Thanks in advance for reading! -- Jacob Greene *Voice Engineer*

2 2

How to ensure separate kamailio instances dispatchers make the same decision?
by George Diamantopoulos 20 May '20

20 May '20

Hello all, I need to have two separate kamailio instances' dispatcher modules make the same decisions when using algorithm 7 (hash over pvar). What do I need to do to ensure this? Note that for design reasons, the two instances cannot share a dispatcher table from db. If I ensure the "setid" group used for algo 7 in the respective cases contain the same group of hosts, is it enough? Do other things matter, such as the ordering of the group members in the table (i.e. different AUTO INCREMENT ids?). Does the setid need to be the same number? Do I need to ensure the 'destination' values are identical (i.e. not using IPs for dispatcher table A and hostnames for dispatcher table B)? If someone knows what the criteria is for matching a hashed pvar to a member of a dispatcher setid and how this can be made deterministic, I would be grateful. Thanks! BR, George

3 3

get_record(): lookup failed
by Chris Atta 20 May '20

20 May '20

get_record(): lookup failed when try to add or invite a user to a private chat room with imc module. join to a public room works!

2 1

s.replace Transformation and Newline
by Carsten Bock 19 May '20

19 May '20

Hi, I am trying the following: - extract the message body - replace some content - store it in a hashtable - send it out in a reply in later request I do the following: [...] $sht(sdp=>$ci) = $(rb{s.replace,a=sendrecv,a=sendrecv\\r\\na=curr:qos local none}); [...] set_reply_body("$sht(sdp=>$ci)", "application/sdp"); send_reply("200", "OK"); [...] It works like a charm, however I'm facing one small issue: My SDP looks like this: [...] a=rtpmap:8 PCMA/8000 a=sendrecv\r\na=curr:qos local none a=rtcp:34949 [...] It seems that s.replace is escaping characters. Anyone any ideas? Thanks, Carsten $var(x) = "abababa"; $(var(x){s.replace,a,c} => "cbcbcbc" -- Carsten Bock I CTO & Founder ng-voice GmbH Trostbrücke 1 I 20457 Hamburg I Germany T +49 40 524 75 93-40 | M +49 179 2021244 I www.ng-voice.com Registry Office at Local Court Hamburg, HRB 120189 Managing Directors: Dr. David Bachmann, Carsten Bock

2 6

rfc: types of announcements on sr-users list
by Daniel-Constantin Mierla 19 May '20

19 May '20

Hello, another topic discussed during the online devel meeting last week was about what kind of announcements should be allowed on this list. The purpose of sr-users list is to be an open community forum to help each other to install, configure and operate Kamailio instances. So far we announced here any new Kamailio versions (obvious) as well as releases to the open source (and free) applications/tools strictly developed for Kamailio (the kamcli and siremis), plus the events related to Kamailio community or project development. Last I am aware of being "accepted" to be announced on this list is the availability to hire Kamailio experts or to be hired, relying on common sense and not abuse of sending often such announcements (which happened so far). Now it came into discussions if announcements about other applications or projects that are built with Kamailio or useful for building/operating Kamailio-based services. Becoming aware of such things can be very valuable for the community, but because this may be interpreted differently (what's useful for me, might not be for others), we should be careful and set the rules to avoid "polluting/spamming" this group of discussions. On the online meeting was somehow considered acceptable to have one time announcement when launching such a project. Clearly this is not the place for announcing every new featured added in external projects or their releases. Moreover, the projects have to be related from real time communications perspective, like: - the project/application is built on top of kamailio (e.g., pbx system with kamailio inside and web ui) - completes what kamailio can do or needs for real time communication services (e.g., monitoring the sip/rtp traffic, billing/charging of sip calls, sip softphone, lib or media server ...) Again, only if these projects/applications are open source and free to use, plus the announcement only when launching the project (or better said, at the moment of the first usable version). Posts to this mailing lists can be done only by members, so who sends the announcement has to be part of the community. If people find it acceptable to get such announcements, I would also consider to revise the policy if we start having more than 2-3 such announcements per month. So, the question for now is if people here would find in useful to have such announcements. Let's see what arguments are for one or the other variant and get to conclusion good for most of us. Cheers, Daniel -- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda

1 1

mid-registrar example
by Easy Rider 19 May '20

19 May '20

Hi everyone I'm trying to implement OpenSIPS-like mid registrar functionality using UAC module Up to now, I was simply performing authentication on my kamailio instance, saving to local domain and forwarding the request to another proxy I'd like to lower the number of requests sent to this other proxy, by forwarding only a few of the incoming requests. This is not a problem to do, but then comes the handling of expiration. If the SIP UAC is disconnected, the registration expires on my kamailio instance, but I don't know how to correctly notify the other proxy on the unregistration (I was trying to build and send a request using uac_send_req(), but this is useless as I can't set the CSeq value) While looking for a solution to my problem, I discovered that OpenSIPS had a mid registrar module doing exactly what I'd like to do (according to the documentation at least). I also saw old messages on this list that it was possible to achieve the same result with UAC module on kamailio In particular, I don't see why (or if) I need to populate the uacreg table. I don't see why it should be necessary, and it would have a great impact on performance to use a db for this matter. I also don't need (I think) to have different local and remote from and to URIs I think I need to build the a new REGISTER request using $uac_req, but I don't see any contact header in the outgoing request, despite having set reg_contact_addr value Thanks for any clue you could give me

4 7

nathelper without usrloc
by Daniel W. Graham 18 May '20

18 May '20

I’ve been missing with this for a while and seem to be missing something. Any suggestions on what is missing here? Trying to use set_contact_alias() and handle_ruri_alias() from nathelper module and nat_keepalive from nat_traversal module, without registrar. I had register keepalive working, that has since broke. When register keepalive was working, I was able to place call in either direction but ACK and BYE was not being routed past kamailio. Registrations are forwarded to the PBX using add_path() and is working. Also not included below is the routing to the PBX, that is just setting $du and t_relay, and is also working. Topology is: UA1 -> NAT -> kamailio -> PBX -> UA2 Using default config file as the example, modified with above changes. I also removed RTP config as that is a non-issue. request_route { …… # FLAG MESSAGES FROM PBX setflag(FLT_PBX); route(NATDETECT); …… route[NATDETECT] { if (nat_uac_test("19")) { force_rport(); set_contact_alias(); nat_keepalive(); } return; } route[WITHINDLG] { if (!has_totag()) return; if (loose_route()) { route(DLGURI); } else if ( is_method("ACK") ) { route(NATMANAGE); } else if ( is_method("NOTIFY") ) { record_route(); } route(RELAY); exit; } if (is_method("SUBSCRIBE") && uri == myself) { route(PRESENCE); exit; } if ( is_method("ACK") ) { if ( t_check_trans() ) { route(RELAY); exit; } else { exit; } } sl_send_reply("404","Not here"); exit; } route[NATMANAGE] { if(isflagset(FLT_PBX)) { handle_ruri_alias(); } if(!isflagset(FLT_PBX)) { set_contact_alias(); } return; } route[DLGURI] { if(!isdsturiset()) { handle_ruri_alias(); } return; } branch_route[MANAGE_BRANCH] { route(NATMANAGE); } onreply_route[MANAGE_REPLY] { if(status=~"[12][0-9][0-9]") { route(NATMANAGE); } } failure_route[MANAGE_FAILURE] { route(NATMANAGE); if (t_is_canceled()) exit; -dan

4 19

msilo dump, ordering
by Péter Barabás 16 May '20

16 May '20

Hi, I have a strange situation in messaging. I start to send messages continuously from one user to another on iOS devices. The messages are stored in silo since receiver is in background and registration does not exists. During the message flow, the receiver registers and the messages are dumped from silo when the sender still sends messages. The order of messages are important because of encryption, if an earlier message arrives after a later one to the receiver, a new key exchange is needed to decrypt messages, etc... So the question: * Is there any technique where I can delay or lock incoming messages on kamailio until silo messages successfully dumped in order to preserve message ordering? Thanks Peter

2 1

Facing issue with call disconnection BYE packets are not accepted by kamailio
by Voip support 15 May '20

15 May '20

Dear Community, I wrote before about my random issues with calls disconnection. We found some issue in our VMWare environment that much packets was lost. We resolved the issue by moving to other VMWare host however the issue is still present. Currently it is random and some calls do not disconnect due to no BYE forwarded by kamailio to other side. Here is the pcap of such call : https://www.dropbox.com/s/7bktz3p4im6ztld/bad-dialog-call.zip?dl=1 We use dialog module and dispatcher. dlg_manage(); is only executed in this block : # - flags # FLT_ - per transaction (message) flags # FLB_ - per branch flags #!define FLT_ACC 1 #!define FLT_ACCMISSED 2 #!define FLT_ACCFAILED 3 #!define FLT_DLG 4 . . . # account only INVITEs if (is_method("INVITE")) { setflag(FLT_DLG); # create dialog << i added it setflag(FLT_ACCMISSED); # do accounting even if failed << i added it setflag(FLT_ACC); # do accounting #route(LIMIT_CALLS); dlg_manage(); sip_trace(); } I really have no idea i am unable to find differences between the bad and good call. The SIP packets looking good but the BYE is not processed. Please help me out how to debug it? I was thinking of adding a log for checking if request is BYE and if it is check if it match a dialog using is_known_dlg() method. Please let me know if you see what is wrong. Just to mention kamailio is listening on private IP with advertise to public IP. Best regards!

2 4

replace SIP methods
by AL RSM 15 May '20

15 May '20

Hi, I was wondering if Kamailio can replace SIP methods? i.e., when Kamailio receives SIP INVITE, change it to NOTIFY methods and fork it to multiple destination. Thanks, AL

2 1

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

2005

2004

2003

2002

sr-users