Hi,
Some more debug information:
User contacts: ser@sen:~/ser/ser-2.0/ssp$ ./serctl/ser_ctl usrloc show touk@voip.rd.touk.pl sip:touk@192.168.0.164:1338;rinstance=191d670c84591ea3;transport=TCP 3330 -1.0 sip:touk@192.168.0.164:1348;rinstance=e51fd20db73fc732;transport=TCP 3405 -1.0
Contents of the location table: mysql> select * from location where uid like 'touk@%'; +----------------------+--------------------------+----------------------------------------------------------------------+----------+---------------------+----+----------------------------------------------+------+-------+----------------------------------+----------+ | uid | aor | contact | received | expires | q | callid | cseq | flags | user_agent | instance | +----------------------+--------------------------+----------------------------------------------------------------------+----------+---------------------+----+----------------------------------------------+------+-------+----------------------------------+----------+ | touk@voip.rd.touk.pl | sip:touk@voip.rd.touk.pl | sip:touk@192.168.0.164:1338;rinstance=191d670c84591ea3;transport=TCP | NULL | 2007-11-23 11:17:03 | -1 | MTQ1ZWZmZWE2MWIxZjViYmVkNzZlNmU0YTEwNjRmMTA. | 4 | 0 | X-Lite release 1011s stamp 41150 | NULL | | touk@voip.rd.touk.pl | sip:touk@voip.rd.touk.pl | sip:touk@192.168.0.164:1348;rinstance=e51fd20db73fc732;transport=TCP | NULL | 2007-11-23 11:18:18 | -1 | NDk0ZDA3ZmU5NmFlZmYyMjA5Mjc2ZTZiYTFmMTMxODY. | 4 | 0 | X-Lite release 1011s stamp 41150 | NULL | +----------------------+--------------------------+----------------------------------------------------------------------+----------+---------------------+----+----------------------------------------------+------+-------+----------------------------------+----------+ 2 rows in set (0.00 sec)
Here is what does the internal proxy (192.168.0.165:7160) which is registrar and perform location service, the destination set contains both contacts and the first contact used is the one which is unavailable. this proxy sends first invite with this contact to boundary sip proxy Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: xlset_attr(bsp_route, <$global_addr_bsp;lr=on>) Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: attr_destination($bsp_route) Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: ($fr_inv_timer) Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: t_on_reply(ONREPLY_ROUTE) Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: t_on_failure(FAILURE_ROUTE) Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: t_on_branch(BRANCH_ROUTE) Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: destination set: Contact: sip:touk@192.168.0.164:1338;rinstance=191d670c84591ea3;transport=TCP, sip:touk@192.168.0.164:1348;rinstance=e51fd20db73fc732;transpo Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: next route: <null> Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: message's next hop: sip:voip.rd.touk.pl:7060;lr=on Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: (!t_relay()) Nov 23 11:20:27 sen ser[28434]: branch_route[BRANCH_ROUTE]: <INVITE> ruri:sip:touk@192.168.0.164:1338;rinstance=191d670c84591ea3;transport=TCP t:sip:touk@voip.rd.touk.pl f:sip:hellboy@voip.rd.touk.pl c Nov 23 11:20:27 sen ser[28434]: branch_route[BRANCH_ROUTE]: append_hf(P-Warn: forked branch) Nov 23 11:20:27 sen ser[28434]: branch_route[BRANCH_ROUTE]: <INVITE> ruri:sip:touk@192.168.0.164:1348;rinstance=e51fd20db73fc732;transport=TCP t:sip:touk@voip.rd.touk.pl f:sip:hellboy@voip.rd.touk.pl c Nov 23 11:20:27 sen ser[28434]: branch_route[BRANCH_ROUTE]: append_hf(P-Warn: forked branch) Nov 23 11:20:27 sen ser[28434]: ERROR: tcp_blocking_connect: poll error: flags 18 Nov 23 11:20:27 sen ser[28434]: ERROR: tcp_blocking_connect: SO_ERROR (111) Connection refused Nov 23 11:20:27 sen ser[28434]: ERROR: tcpconn_connect: tcp_blocking_connect failed Nov 23 11:20:27 sen ser[28434]: ERROR: tcp_send: connect failed Nov 23 11:20:27 sen ser[28434]: msg_send: ERROR: tcp_send failed Nov 23 11:20:27 sen ser[28434]: ERROR: t_send_branch: sending request on branch 1 failed Nov 23 11:20:27 sen ser[28434]: route[FORWARD]: drop
here is what happens in boundary sip proxy: is receives the invite tries to do the t_rqlay but there is the tcp error. t_relay() fails and it sends the sl_reply_error to internal sip proxy: Nov 23 11:31:38 sen ser[31861]: route[FORWARD]: destination set: <null> Nov 23 11:31:38 sen ser[31861]: route[FORWARD]: next route: <null> Nov 23 11:31:38 sen ser[31861]: route[FORWARD]: message's next hop: sip:touk@192.168.0.164:1338;rinstance=191d670c84591ea3;transport=TCP Nov 23 11:31:38 sen ser[31861]: route[FORWARD]: (!t_relay()) Nov 23 11:31:38 sen ser[31861]: ERROR: tcp_blocking_connect: poll error: flags 18 Nov 23 11:31:38 sen ser[31861]: ERROR: tcp_blocking_connect: SO_ERROR (111) Connection refused Nov 23 11:31:38 sen ser[31861]: ERROR: tcpconn_connect: tcp_blocking_connect failed Nov 23 11:31:38 sen ser[31861]: ERROR: tcp_send: connect failed Nov 23 11:31:38 sen ser[31861]: msg_send: ERROR: tcp_send failed Nov 23 11:31:38 sen ser[31861]: ERROR: t_send_branch: sending request on branch 0 failed Nov 23 11:31:38 sen ser[31861]: route[FORWARD]: (!t_relay()) -> TRUE Nov 23 11:31:38 sen ser[31861]: route[FORWARD]: sl_reply_error() Nov 23 11:31:38 sen ser[31861]: ERROR: sl_reply_error used: I'm terribly sorry, server error occurred (1/SL)
in the attachment there are ngrep and wireshark log from the same situation
if you need more info please let me know waiting for your feedback
Cheers tomasz
On Nov 22, 2007 8:40 PM, Andrei Pelinescu-Onciul andrei@iptel.org wrote:
-
Tomasz Zieleniewski