i turned on debug and here are the last words (after successful radius authorization) from the proxy before it becomes unresponsive while handling the register request. looks to me that it happens while proxy is executing save("location") call. after save there is just exit. 200 ok never leaves the proxy.
-- juha
Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: INFO: xlog [xlog.c:186]: INFO: REGISTER sip:test@test.fi by test@test.fi from <192.98.101.10> is authorized Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: htable [ht_api.c:112]: htable found [auth] Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: permissions [address.c:383]: looking for <2, a6562c0, 5074> Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=88 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: permissions [permissions.c:784]: looking for To: sip:test@test.fi Contact: sip:test@192.98.101.10 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=80 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:89]: found end of header Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: permissions [permissions.c:803]: no contact denied => Allowed Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=ffffffffffffffff Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=8000000 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: registrar [save.c:489]: 2 valid contacts Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: registrar [save.c:511]: 1 contacts after commit Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: INFO: db_mysql [km_dbase.c:98]: submit_query(): delete from location where username='test' AND contact='sip:test@192.98.101.10:5074;transport=udp' AND callid='fvatirvfbqfpevk@localhost' AND domain='test.fi' Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: registrar [reply.c:192]: created Contact HF: Contact: sip:test@192.98.101.10:5074;transport=tcp;q=0.5;expires=3359#015#012 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: sl [sl.c:314]: reply in stateless mode (sl) Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=ffffffffffffffff Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [msg_translator.c:200]: check_via_address(192.98.101.10, 192.98.101.10, 0) Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [usr_avp.c:629]: DEBUG:destroy_avp_list: destroying list 0xb338dfb0 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [usr_avp.c:629]: DEBUG:destroy_avp_list: destroying list (nil) Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [usr_avp.c:629]: DEBUG:destroy_avp_list: destroying list (nil) Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [usr_avp.c:629]: DEBUG:destroy_avp_list: destroying list (nil) Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [usr_avp.c:629]: DEBUG:destroy_avp_list: destroying list (nil) Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [usr_avp.c:629]: DEBUG:destroy_avp_list: destroying list (nil) Jun 4 18:51:11 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [receive.c:244]: receive_msg: cleaning up Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:594]: SIP Request: Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:596]: method: <NOTIFY> Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:598]: uri: sip:test@192.98.101.10:5074;transport=udp Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:600]: version: <SIP/2.0> Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=2 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/parse_via.c:1283]: Found param type 232, <branch> = <z9hG4bKce8f.7aa61b05.0>; state=16 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/parse_via.c:2296]: end of header reached, state=5 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:493]: parse_headers: Via found, flags=2 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:495]: parse_headers: this is the first via Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [receive.c:120]: After parse_msg... Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [receive.c:156]: preparing to run routing scripts... Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [msg_translator.c:200]: check_via_address(192.98.101.10, 192.98.101.10, 0) Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=100 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/parse_to.c:174]: DEBUG: add_param: tag=oggsp Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/parse_to.c:753]: end of header reached, state=29 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:173]: DEBUG: get_hdr_field: <To> [30]; uri=[sip:test@test.fi] Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:175]: DEBUG: to body [sip:test@test.fi] Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field: cseq <CSeq>: <1> <NOTIFY> Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_body : content_length=0 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: lcr [lcr_mod.c:2227]: request did not come from gw Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: siputils [checks.c:68]: totag found Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=200 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: rr [loose.c:85]: is_preloaded: No Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5074 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==9 && [192.98.101.10] == [127.0.0.1] Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5070 matches port 5074 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==9 && [192.98.101.10] == [127.0.0.1] Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5080 matches port 5074 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5074 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5074 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [forward.c:298]: check_self: host != me Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5060 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: rr [loose.c:781]: Topmost route URI: 'sip:192.98.101.10;lr;ftag=oggsp' is me Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=200 Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: <core> [parser/msg_parser.c:89]: found end of header Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: rr [loose.c:257]: No next Route HF found Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: rr [loose.c:800]: No next URI found Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: rr [rr_cb.c:97]: callback id 0 entered with <lr;ftag=oggsp> Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: uac [from.c:421]: getting 'vsf' Route param Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: uac [from.c:425]: route param 'vsf' not found Jun 4 18:51:11 localhost /usr/sbin/sip-router[8252]: DEBUG: rr [loose.c:968]: params are <;lr;ftag=oggsp> Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:594]: SIP Request: Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:596]: method: <NOTIFY> Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:598]: uri: sip:test@192.98.101.10:5074;transport=udp Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:600]: version: <SIP/2.0> Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=2 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/parse_via.c:1283]: Found param type 232, <branch> = <z9hG4bKce8f.7aa61b05.0>; state=16 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/parse_via.c:2296]: end of header reached, state=5 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:493]: parse_headers: Via found, flags=2 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:495]: parse_headers: this is the first via Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [receive.c:120]: After parse_msg... Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [receive.c:156]: preparing to run routing scripts... Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [msg_translator.c:200]: check_via_address(192.98.101.10, 192.98.101.10, 0) Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=100 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/parse_to.c:174]: DEBUG: add_param: tag=oggsp Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/parse_to.c:753]: end of header reached, state=29 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:173]: DEBUG: get_hdr_field: <To> [30]; uri=[sip:test@test.fi] Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:175]: DEBUG: to body [sip:test@test.fi] Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field: cseq <CSeq>: <1> <NOTIFY> Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_body : content_length=0 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: lcr [lcr_mod.c:2227]: request did not come from gw Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: siputils [checks.c:68]: totag found Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=200 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: rr [loose.c:85]: is_preloaded: No Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5074 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==9 && [192.98.101.10] == [127.0.0.1] Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5070 matches port 5074 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==9 && [192.98.101.10] == [127.0.0.1] Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5080 matches port 5074 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5074 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5074 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [forward.c:298]: check_self: host != me Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5060 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: rr [loose.c:781]: Topmost route URI: 'sip:192.98.101.10;lr;ftag=oggsp' is me Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=200 Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: <core> [parser/msg_parser.c:89]: found end of header Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: rr [loose.c:257]: No next Route HF found Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: rr [loose.c:800]: No next URI found Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: rr [rr_cb.c:97]: callback id 0 entered with <lr;ftag=oggsp> Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: uac [from.c:421]: getting 'vsf' Route param Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: uac [from.c:425]: route param 'vsf' not found Jun 4 18:51:12 localhost /usr/sbin/sip-router[8251]: DEBUG: rr [loose.c:968]: params are <;lr;ftag=oggsp> Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:594]: SIP Request: Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:596]: method: <NOTIFY> Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:598]: uri: sip:test@192.98.101.10:5074;transport=udp Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:600]: version: <SIP/2.0> Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=2 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/parse_via.c:1283]: Found param type 232, <branch> = <z9hG4bKce8f.7aa61b05.0>; state=16 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/parse_via.c:2296]: end of header reached, state=5 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:493]: parse_headers: Via found, flags=2 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:495]: parse_headers: this is the first via Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [receive.c:120]: After parse_msg... Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [receive.c:156]: preparing to run routing scripts... Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [msg_translator.c:200]: check_via_address(192.98.101.10, 192.98.101.10, 0) Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=100 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/parse_to.c:174]: DEBUG: add_param: tag=oggsp Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/parse_to.c:753]: end of header reached, state=29 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:173]: DEBUG: get_hdr_field: <To> [30]; uri=[sip:test@test.fi] Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:175]: DEBUG: to body [sip:test@test.fi] Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field: cseq <CSeq>: <1> <NOTIFY> Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_body : content_length=0 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: lcr [lcr_mod.c:2227]: request did not come from gw Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: siputils [checks.c:68]: totag found Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=200 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: rr [loose.c:85]: is_preloaded: No Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5074 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==9 && [192.98.101.10] == [127.0.0.1] Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5070 matches port 5074 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==9 && [192.98.101.10] == [127.0.0.1] Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5080 matches port 5074 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5074 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5074 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [forward.c:298]: check_self: host != me Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:484]: grep_sock_info - checking if host==us: 13==13 && [192.98.101.10] == [192.98.101.10] Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [socket_info.c:487]: grep_sock_info - checking if port 5060 matches port 5060 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: rr [loose.c:781]: Topmost route URI: 'sip:192.98.101.10;lr;ftag=oggsp' is me Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:318]: parse_headers: flags=200 Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: <core> [parser/msg_parser.c:89]: found end of header Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: rr [loose.c:257]: No next Route HF found Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: rr [loose.c:800]: No next URI found Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: rr [rr_cb.c:97]: callback id 0 entered with <lr;ftag=oggsp> Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: uac [from.c:421]: getting 'vsf' Route param Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: uac [from.c:425]: route param 'vsf' not found Jun 4 18:51:14 localhost /usr/sbin/sip-router[8249]: DEBUG: rr [loose.c:968]: params are <;lr;ftag=oggsp>