Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: INFO: <script>: New request - M=REGISTER R=sip:110442@performance.lab.pt:5060 F=sip:110442@performance.lab.pt T=sip:110442@performance.lab.pt IP=udp:100.64.0.1:5071 - ID=afa6ed2c62c14bf7aaa2d61d92ad0f06
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13683]: DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13685]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG: t_reply_matching: hash 62436 label 0 branch 0
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [select.c:429]: run_select(): Calling SELECT 0x7f4cff8a9ae8 
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13683]: DEBUG: <core> [receive.c:298]: receive_msg(): receive_msg: cleaning up
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13685]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f4cf5cf8308)!
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [select.c:429]: run_select(): Calling SELECT 0x7f4cff8cffd0 
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13685]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7f4cf5cf8308, callback type 2, id 0 entered
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: sl [sl_funcs.c:515]: sl_run_callbacks(): execute callback for event type 1
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13685]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [parser/parse_addr_spec.c:176]: parse_to_param(): DEBUG: add_param: tag=48bc57fa80de4ed79ff91b522040328e
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: siptrace [siptrace.c:1840]: pipport2su(): the port string is 5060
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13685]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [parser/parse_addr_spec.c:898]: parse_addr_spec(): end of header reached, state=29
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: siptrace [siptrace.c:1840]: pipport2su(): the port string is 5071
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13685]: DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: siptrace [siptrace.c:1840]: pipport2su(): the port string is 5060
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [proxy.c:278]: mk_proxy(): DEBUG: mk_proxy: doing DNS lookup...
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13685]: DEBUG: <core> [receive.c:298]: receive_msg(): receive_msg: cleaning up
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: siptrace [siptrace.c:1840]: pipport2su(): the port string is 5062
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: siptrace [siptrace.c:1652]: trace_send_hep_duplicate(): setting up the socket_info
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [proxy.c:278]: mk_proxy(): DEBUG: mk_proxy: doing DNS lookup...
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [forward.c:211]: get_out_socket(): DEBUG: get_out_socket: socket determined: 0x7f4cff85d7a8
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: siptrace [siptrace.c:1652]: trace_send_hep_duplicate(): setting up the socket_info
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: siputils [checks.c:103]: has_totag(): no totag
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [forward.c:211]: get_out_socket(): DEBUG: get_out_socket: socket determined: 0x7f4cff85d7a8
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: rr [loose.c:113]: find_first_route(): No Route headers found
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=85 global id=85 T end=0x7f4cf5cf8308
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: rr [loose.c:929]: loose_route(): There is no Route HF
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: tm [t_reply.c:2200]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=100 local=0 is_invite=0)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: dispatcher [dispatch.c:1643]: ds_select_dst_limit(): set [9]
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: tm [t_reply.c:1294]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: dispatcher [dispatch.c:1745]: ds_select_dst_limit(): alg hash [0]
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: tm [t_reply.c:1812]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: ERROR: <script>: Failed to select proxy - R=sip:110442@performance.lab.pt:5060 ID=afa6ed2c62c14bf7aaa2d61d92ad0f06
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [msg_translator.c:2276]: generate_res_buf_from_sip_res():  old size: 664, new size: 489
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: sl [sl_funcs.c:515]: sl_run_callbacks(): execute callback for event type 1
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [msg_translator.c:2294]: generate_res_buf_from_sip_res(): copied size: orig:528, new: 353, rest: 136 msg=#012SIP/2.0 200 OK#015#012Via: SIP/2.0/UDP 192.168.1.237:5071;received=100.64.0.1;rport=5071;branch=z9hG4bKPjcd0e1de12662406aa34e663e037e7861#015#012From: <sip:110483@performance.lab.pt>;tag=48bc57fa80de4ed79ff91b522040328e#015#012To: <sip:110483@performance.lab.pt>;tag=a36eaaeec4617e6f6fa6b4aae8465208.5bd6#015#012Call-ID: 74f75e238e6c4fbb9376251197f0564b#015#012CSeq: 31438 REGISTER#015#012Contact: <sip:110483@192.168.1.237:5071>;expires=60;received="sip:100.64.0.1:5071"#015#012Server: Voicis VSIP Proxy 1.X#015#012Content-Length: 0#015#012#015#012
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: siptrace [siptrace.c:1840]: pipport2su(): the port string is 5060
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [mem/shm_mem.c:111]: _shm_resize(): WARNING:vqm_resize: resize(0) called
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: siptrace [siptrace.c:1840]: pipport2su(): the port string is 5071
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7f4cf5cf8308, callback type 8388608, id 0 entered
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [proxy.c:278]: mk_proxy(): DEBUG: mk_proxy: doing DNS lookup...
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: siptrace [siptrace.c:1840]: pipport2su(): the port string is 5060
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: siptrace [siptrace.c:1652]: trace_send_hep_duplicate(): setting up the socket_info
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: siptrace [siptrace.c:1840]: pipport2su(): the port string is 5071
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [forward.c:211]: get_out_socket(): DEBUG: get_out_socket: socket determined: 0x7f4cff85d7a8
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [proxy.c:278]: mk_proxy(): DEBUG: mk_proxy: doing DNS lookup...
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: siptrace [siptrace.c:1652]: trace_send_hep_duplicate(): setting up the socket_info
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [forward.c:211]: get_out_socket(): DEBUG: get_out_socket: socket determined: 0x7f4cff85d7a8
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: tm [t_reply.c:1653]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13690]: DEBUG: <core> [receive.c:298]: receive_msg(): receive_msg: cleaning up
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [usr_avp.c:643]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [xavp.c:448]: xavp_destroy_list(): destroying xavp list (nil)
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13633]: DEBUG: <core> [receive.c:298]: receive_msg(): receive_msg: cleaning up
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [parser/msg_parser.c:623]: parse_msg(): SIP Request:
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [parser/msg_parser.c:625]: parse_msg():  method:  <REGISTER>
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [parser/msg_parser.c:627]: parse_msg():  uri:     <sip:110479@performance.lab.pt:5060>
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [parser/msg_parser.c:629]: parse_msg():  version: <SIP/2.0>
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=6
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKPj6552e8849e3247fba64f2ffca2f6746d>; state=16
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [receive.c:154]: receive_msg(): After parse_msg...
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: <core> [receive.c:197]: receive_msg(): preparing to run routing scripts...
Dec 18 12:21:13 slb1 /usr/local/sbin/kamailio[13686]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70