Here debug of first invite, but I can't see. what actually not right.
7(4161) INFO: <script>: This is 1200 and 1200
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=757 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=739 a=25 n=lookup
7(4161) DEBUG: registrar [lookup.c:181]: lookup(): contact for [1200@networklab.loc] found by address
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=762 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=757 a=25 n=is_method
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=759 a=39 n=setflag
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=762 a=5 n=route
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=605 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=601 a=25 n=is_method
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=602 a=25 n=t_on_branch
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=603 a=25 n=t_on_reply
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=609 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=605 a=25 n=is_method
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=606 a=25 n=t_on_failure
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=612 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=609 a=24 n=t_relay
7(4161) DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=13 , global msg id=13 , T on entrance=(nil)
7(4161) DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=53747, isACK=0
7(4161) DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching failed
7(4161) DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
7(4161) DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7fc3ea9cf688, callback type 1, id 0 entered
7(4161) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): DEBUG: MD5 calculated: aec714b86f229713d513ffed6ac538f3
7(4161) DEBUG: tm [t_funcs.c:347]: t_relay_to(): SER: new INVITE
7(4161) DEBUG: <core> [msg_translator.c:204]: check_via_address(): check_via_address(192.168.10.116, 192.168.10.116, 0)
7(4161) DEBUG: <core> [mem/shm_mem.c:111]: _shm_resize(): WARNING:vqm_resize: resize(0) called
7(4161) DEBUG: tm [t_reply.c:728]: _reply_light(): DEBUG: reply sent out. buf=0x7fc3f639f7d8: SIP/2.0 100 trying -..., shmem=0x7fc3ea9cb100: SIP/2.0 100 trying -
7(4161) DEBUG: tm [t_reply.c:738]: _reply_light(): DEBUG: _reply_light: finished
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=1003 a=25 n=xdbg
7(4161) DEBUG: <script>: new branch [0] to sip:1240@192.168.10.116:48961;transport=UDP;alias=192.168.10.116~48961~1
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=1004 a=5 n=route
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=861 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=854 a=24 n=is_request
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=860 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=855 a=24 n=has_totag
7(4161) DEBUG: siputils [checks.c:103]: has_totag(): no totag
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=866 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=861 a=41 n=isflagset
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=866 a=24 n=rtpproxy_manage
7(4161) DEBUG: <core> [parser/sdp/sdp_helpr_funcs.c:565]: extract_mediaip(): located IP address [127.0.0.1] in `o=' field
7(4161) DEBUG: <core> [parser/sdp/sdp_helpr_funcs.c:565]: extract_mediaip(): located IP address [127.0.0.1] in `c=' field
7(4161) DEBUG: rtpproxy [rtpproxy_funcs.c:148]: check_content_type(): type <application/sdp> found valid
7(4161) DEBUG: rtpproxy [rtpproxy.c:2696]: force_rtp_proxy(): proxy reply: 43056 192.168.10.120
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=873 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=868 a=24 n=is_request
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=872 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=869 a=24 n=has_totag
7(4161) DEBUG: siputils [checks.c:103]: has_totag(): no totag
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=870 a=25 n=add_rr_param
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=881 a=16 n=if
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=873 a=24 n=is_reply
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=881 a=2 n=return
7(4161) DEBUG: <core> [msg_translator.c:456]: clen_builder(): clen_builder: content-length: 184 (184)
7(4161) DEBUG: <core> [msg_translator.c:204]: check_via_address(): check_via_address(192.168.10.116, 192.168.10.116, 0)
7(4161) DEBUG: tm [t_funcs.c:394]: t_relay_to(): SER: new transaction fwd'ed
7(4161) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=612 a=2 n=exit
7(4161) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
7(4161) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
7(4161) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
7(4161) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
7(4161) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
7(4161) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
7(4161) DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp list (nil)
7(4161) DEBUG: <core> [receive.c:296]: receive_msg(): receive_msg: cleaning up
7(4161) DEBUG: <core> [parser/sdp/sdp.c:793]: free_sdp(): _sdp = 0x7fc3f639b728
7(4161) DEBUG: <core> [parser/sdp/sdp.c:795]: free_sdp(): sdp = 0x7fc3f639ecd0
7(4161) DEBUG: <core> [parser/sdp/sdp.c:797]: free_sdp(): session = 0x7fc3f639c3d0
6(4160) DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply (status):
6(4160) DEBUG: <core> [parser/msg_parser.c:635]: parse_msg(): version: <SIP/2.0>
6(4160) DEBUG: <core> [parser/msg_parser.c:637]: parse_msg(): status: <100>
6(4160) DEBUG: <core> [parser/msg_parser.c:639]: parse_msg(): reason: <Trying>
6(4160) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK3f1d.aec714b86f229713d513ffed6ac538f3.0>; state=16
6(4160) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
6(4160) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
6(4160) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
6(4160) DEBUG: <core> [receive.c:152]: receive_msg(): After parse_msg...
6(4160) DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=13 global id=11 T start=(nil)
6(4160) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK-d8754z-88fe2d7ab6e1e390-1---d8754z->; state=6
6(4160) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <48961>; state=16
6(4160) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
6(4160) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=62
6(4160) DEBUG: <core> [parser/msg_parser.c:526]: parse_headers(): parse_headers: this is the second via
6(4160) DEBUG: <core> [parser/parse_addr_spec.c:893]: parse_addr_spec(): end of header reached, state=10
6(4160) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [41]; uri=[sip:1200@networklab.loc;transport=UDP]
6(4160) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:1200@networklab.loc;transport=UDP>
]
6(4160) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <2> <INVITE>
6(4160) DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG: t_reply_matching: hash 53747 label 0 branch 0
6(4160) DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fc3ea9cf688)!
6(4160) DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7fc3ea9cf688, callback type 2, id 0 entered
6(4160) DEBUG: acc [acc_logic.c:557]: tmcb_func(): acc callback called for t(0x7fc3ea9cf688) event type 2, reply code 100
6(4160) DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=13 global id=13 T end=0x7fc3ea9cf688
6(4160) DEBUG: tm [t_reply.c:2206]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1)
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=1009 a=25 n=xdbg
6(4160) DEBUG: <script>: incoming reply
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=1012 a=16 n=if
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=1011 a=5 n=route
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=861 a=16 n=if
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=854 a=24 n=is_request
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=866 a=16 n=if
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=861 a=41 n=isflagset
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=866 a=24 n=rtpproxy_manage
6(4160) DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
6(4160) DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
6(4160) DEBUG: <core> [parser/sdp/sdp.c:712]: parse_sdp(): message body has length zero
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=873 a=16 n=if
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=868 a=24 n=is_request
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=881 a=16 n=if
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=873 a=24 n=is_reply
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=879 a=16 n=if
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=874 a=25 n=isbflagset
6(4160) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=881 a=2 n=return
6(4160) DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=100
6(4160) DEBUG: tm [t_reply.c:1822]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=-1 icode=0
6(4160) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
6(4160) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
6(4160) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
6(4160) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
6(4160) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
6(4160) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
6(4160) DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp list (nil)
6(4160) DEBUG: <core> [receive.c:296]: receive_msg(): receive_msg: cleaning up
8(4162) DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply (status):
8(4162) DEBUG: <core> [parser/msg_parser.c:635]: parse_msg(): version: <SIP/2.0>
8(4162) DEBUG: <core> [parser/msg_parser.c:637]: parse_msg(): status: <180>
8(4162) DEBUG: <core> [parser/msg_parser.c:639]: parse_msg(): reason: <Ringing>
8(4162) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK3f1d.aec714b86f229713d513ffed6ac538f3.0>; state=16
8(4162) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
8(4162) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
8(4162) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
8(4162) DEBUG: <core> [receive.c:152]: receive_msg(): After parse_msg...
8(4162) DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=15 global id=14 T start=(nil)
8(4162) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK-d8754z-88fe2d7ab6e1e390-1---d8754z->; state=6
8(4162) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <48961>; state=16
8(4162) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
8(4162) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=62
8(4162) DEBUG: <core> [parser/msg_parser.c:526]: parse_headers(): parse_headers: this is the second via
8(4162) DEBUG: <core> [parser/parse_addr_spec.c:176]: parse_to_param(): DEBUG: add_param: tag=c23f2e5e
8(4162) DEBUG: <core> [parser/parse_addr_spec.c:893]: parse_addr_spec(): end of header reached, state=29
8(4162) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [54]; uri=[sip:1200@networklab.loc;transport=UDP]
8(4162) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:1200@networklab.loc;transport=UDP>]
8(4162) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <2> <INVITE>
8(4162) DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG: t_reply_matching: hash 53747 label 0 branch 0
8(4162) DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fc3ea9cf688)!
8(4162) DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7fc3ea9cf688, callback type 2, id 0 entered
8(4162) DEBUG: acc [acc_logic.c:557]: tmcb_func(): acc callback called for t(0x7fc3ea9cf688) event type 2, reply code 180
8(4162) DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=15 global id=15 T end=0x7fc3ea9cf688
8(4162) DEBUG: tm [t_reply.c:2206]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1)
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=1009 a=25 n=xdbg
8(4162) DEBUG: <script>: incoming reply
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=1012 a=16 n=if
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=1011 a=5 n=route
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=861 a=16 n=if
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=854 a=24 n=is_request
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=866 a=16 n=if
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=861 a=41 n=isflagset
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=866 a=24 n=rtpproxy_manage
8(4162) DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
8(4162) DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
8(4162) DEBUG: <core> [parser/sdp/sdp.c:712]: parse_sdp(): message body has length zero
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=873 a=16 n=if
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=868 a=24 n=is_request
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=881 a=16 n=if
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=873 a=24 n=is_reply
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=879 a=16 n=if
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=874 a=25 n=isbflagset
8(4162) ERROR: *** cfgtrace: c=[/etc/kamailio/kamailio-ldap.cfg] l=881 a=2 n=return
8(4162) DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>> T_code=100, new_code=180
8(4162) DEBUG: tm [t_reply.c:1822]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0
8(4162) DEBUG: <core> [msg_translator.c:2007]: generate_res_buf_from_sip_res(): old size: 596, new size: 509
8(4162) DEBUG: <core> [msg_translator.c:2025]: generate_res_buf_from_sip_res(): copied size: orig:108, new: 21, rest: 488 msg=
Slava.