26(174) DEBUG: tm [h_table.c:133]: free_cell_helper(): freeing transaction 0x7f289269dd08 from timer.c:654
16(164) DEBUG: <core> [core/udp_server.c:514]: udp_rcv_loop(): probing packet received from 192.168.100.28 50195
13(161) DEBUG: <core> [core/udp_server.c:491]: udp_rcv_loop(): received on udp socket: (106/100/820) [[INVITE sip:safarov@aio.kazoo SIP/2.0 0D  0A Via: SIP/2.0/UDP 192.168.100.31:35060;rport;branch=z9hG4bK1459]]
13(161) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): SIP Request:
13(161) DEBUG: <core> [core/parser/msg_parser.c:612]: parse_msg():  method:  <INVITE>
13(161) DEBUG: <core> [core/parser/msg_parser.c:614]: parse_msg():  uri:     <sip:safarov@aio.kazoo>
13(161) DEBUG: <core> [core/parser/msg_parser.c:616]: parse_msg():  version: <SIP/2.0>
13(161) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6
13(161) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK1459394859>; state=16
13(161) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5
13(161) DEBUG: <core> [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2
13(161) DEBUG: <core> [core/parser/msg_parser.c:500]: parse_headers(): this is the first via
13(161) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10
13(161) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [25]; uri=[sip:safarov@aio.kazoo]
13(161) DEBUG: <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body [<sip:safarov@aio.kazoo>
], to tag []
13(161) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <20> <INVITE>
13(161) DEBUG: <core> [core/receive.c:240]: receive_msg(): --- received sip message - request - call-id: [392320003] - cseq: [20 INVITE]
13(161) DEBUG: <core> [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=333
13(161) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/receive.c:295]: receive_msg(): preparing to run routing scripts...
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=471 a=16 n=if
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=463 a=25 n=is_method
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=464 a=27 n=set_body_multipart
13(161) DEBUG: {1 20 INVITE 392320003} textops [textops.c:2248]: ki_set_multibody(): delimiter<9>:[delimiter]
13(161) DEBUG: {1 20 INVITE 392320003} textops [textops.c:2139]: generate_boundary(): adding final CRLF+CRLF
13(161) DEBUG: {1 20 INVITE 392320003} textops [textops.c:2367]: ki_set_multibody(): content-type<52>:[Content-Type: multipart/mixed;boundary="delimiter"
]
13(161) DEBUG: {1 20 INVITE 392320003} textops [textops.c:2404]: ki_set_multibody(): set flag FL_BODY_MULTIPART
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=465 a=24 n=msg_apply_changes
13(161) INFO: {1 20 INVITE 392320003} <core> [core/msg_translator.c:1716]: get_boundary(): Content-Type hdr has no params <application/sdp>
13(161) WARNING: {1 20 INVITE 392320003} <core> [core/msg_translator.c:1983]: build_req_buf_from_sip_req(): check_boundaries error
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/msg_translator.c:432]: clen_builder(): content-length: 60 (60)
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/msg_translator.c:3258]: sip_msg_update_buffer(): SIP message content updated - reparsing
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/msg_parser.c:610]: parse_msg(): SIP Request:
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/msg_parser.c:612]: parse_msg():  method:  <INVITE>
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/msg_parser.c:614]: parse_msg():  uri:     <sip:safarov@aio.kazoo>
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/msg_parser.c:616]: parse_msg():  version: <SIP/2.0>
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK1459394859>; state=16
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/msg_parser.c:500]: parse_headers(): this is the first via
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=471 a=5 n=route
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=583 a=16 n=if
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [25]; uri=[sip:safarov@aio.kazoo]
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body [<sip:safarov@aio.kazoo>
], to tag []
13(161) DEBUG: {1 20 INVITE 392320003} <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <20> <INVITE>
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=588 a=16 n=if
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=583 a=25 n=mf_process_maxfwd_header
13(161) DEBUG: {1 20 INVITE 392320003} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=593 a=16 n=if
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=588 a=25 n=is_method
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=597 a=16 n=if
13(161) exec: {1 20 INVITE 392320003} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=593 a=26 n=sanity_check
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:247]: check_ruri_sip_version(): check_ruri_sip_version entered
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:270]: check_ruri_sip_version(): check_ruri_sip_version passed
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:277]: check_ruri_scheme(): check_ruri_scheme entered
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:297]: check_ruri_scheme(): check_ruri_scheme passed
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:305]: check_required_headers(): check_required_headers entered
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:313]: check_required_headers(): check_required_headers passed
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:321]: check_via1_header(): check via1 header
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:479]: check_cseq_method(): check_cseq_method entered
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:512]: check_cseq_method(): check_cseq_method passed
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:521]: check_cseq_value(): check_cseq_value entered
13(161) DEBUG: {1 20 INVITE 392320003} sanity [sanity.c:549]: check_cseq_value(): check_cseq_value passed