<html xmlns:v="urn:schemas-microsoft-com:vml" xmlns:o="urn:schemas-microsoft-com:office:office" xmlns:w="urn:schemas-microsoft-com:office:word" xmlns:x="urn:schemas-microsoft-com:office:excel" xmlns:p="urn:schemas-microsoft-com:office:powerpoint" xmlns:a="urn:schemas-microsoft-com:office:access" xmlns:dt="uuid:C2F41010-65B3-11d1-A29F-00AA00C14882" xmlns:s="uuid:BDC6E3F0-6DA3-11d1-A2A3-00AA00C14882" xmlns:rs="urn:schemas-microsoft-com:rowset" xmlns:z="#RowsetSchema" xmlns:b="urn:schemas-microsoft-com:office:publisher" xmlns:ss="urn:schemas-microsoft-com:office:spreadsheet" xmlns:c="urn:schemas-microsoft-com:office:component:spreadsheet" xmlns:odc="urn:schemas-microsoft-com:office:odc" xmlns:oa="urn:schemas-microsoft-com:office:activation" xmlns:html="http://www.w3.org/TR/REC-html40" xmlns:q="http://schemas.xmlsoap.org/soap/envelope/" xmlns:rtc="http://microsoft.com/officenet/conferencing" xmlns:D="DAV:" xmlns:Repl="http://schemas.microsoft.com/repl/" xmlns:mt="http://schemas.microsoft.com/sharepoint/soap/meetings/" xmlns:x2="http://schemas.microsoft.com/office/excel/2003/xml" xmlns:ppda="http://www.passport.com/NameSpace.xsd" xmlns:ois="http://schemas.microsoft.com/sharepoint/soap/ois/" xmlns:dir="http://schemas.microsoft.com/sharepoint/soap/directory/" xmlns:ds="http://www.w3.org/2000/09/xmldsig#" xmlns:dsp="http://schemas.microsoft.com/sharepoint/dsp" xmlns:udc="http://schemas.microsoft.com/data/udc" xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:sub="http://schemas.microsoft.com/sharepoint/soap/2002/1/alerts/" xmlns:ec="http://www.w3.org/2001/04/xmlenc#" xmlns:sp="http://schemas.microsoft.com/sharepoint/" xmlns:sps="http://schemas.microsoft.com/sharepoint/soap/" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:udcs="http://schemas.microsoft.com/data/udc/soap" xmlns:udcxf="http://schemas.microsoft.com/data/udc/xmlfile" xmlns:udcp2p="http://schemas.microsoft.com/data/udc/parttopart" xmlns:wf="http://schemas.microsoft.com/sharepoint/soap/workflow/" xmlns:dsss="http://schemas.microsoft.com/office/2006/digsig-setup" xmlns:dssi="http://schemas.microsoft.com/office/2006/digsig" xmlns:mdssi="http://schemas.openxmlformats.org/package/2006/digital-signature" xmlns:mver="http://schemas.openxmlformats.org/markup-compatibility/2006" xmlns:m="http://schemas.microsoft.com/office/2004/12/omml" xmlns:mrels="http://schemas.openxmlformats.org/package/2006/relationships" xmlns:spwp="http://microsoft.com/sharepoint/webpartpages" xmlns:ex12t="http://schemas.microsoft.com/exchange/services/2006/types" xmlns:ex12m="http://schemas.microsoft.com/exchange/services/2006/messages" xmlns:pptsl="http://schemas.microsoft.com/sharepoint/soap/SlideLibrary/" xmlns:spsl="http://microsoft.com/webservices/SharePointPortalServer/PublishedLinksService" xmlns:Z="urn:schemas-microsoft-com:" xmlns:st="" xmlns="http://www.w3.org/TR/REC-html40">
<head>
<meta http-equiv=Content-Type content="text/html; charset=us-ascii">
<meta name=Generator content="Microsoft Word 12 (filtered medium)">
<style>
<!--
/* Font Definitions */
@font-face
{font-family:"Cambria Math";
panose-1:2 4 5 3 5 4 6 3 2 4;}
@font-face
{font-family:Calibri;
panose-1:2 15 5 2 2 2 4 3 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
{margin:0in;
margin-bottom:.0001pt;
font-size:11.0pt;
font-family:"Calibri","sans-serif";}
a:link, span.MsoHyperlink
{mso-style-priority:99;
color:blue;
text-decoration:underline;}
a:visited, span.MsoHyperlinkFollowed
{mso-style-priority:99;
color:purple;
text-decoration:underline;}
span.EmailStyle17
{mso-style-type:personal;
font-family:"Calibri","sans-serif";
color:windowtext;}
span.EmailStyle18
{mso-style-type:personal-reply;
font-family:"Calibri","sans-serif";
color:#1F497D;}
.MsoChpDefault
{mso-style-type:export-only;
font-size:10.0pt;}
@page Section1
{size:8.5in 11.0in;
margin:1.0in 1.0in 1.0in 1.0in;}
div.Section1
{page:Section1;}
-->
</style>
<!--[if gte mso 9]><xml>
<o:shapedefaults v:ext="edit" spidmax="1026" />
</xml><![endif]--><!--[if gte mso 9]><xml>
<o:shapelayout v:ext="edit">
<o:idmap v:ext="edit" data="1" />
</o:shapelayout></xml><![endif]-->
</head>
<body lang=EN-US link=blue vlink=purple>
<div class=Section1>
<p class=MsoNormal>Hello there,<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>I am trying to create a seperate Presence server to use with
my existing OpenSIPS proxy. My main proxy/registrar listens at 10.1.1.1 port
5080, while the presence server listens to 10.1.1.1 5061.<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>The registrar/proxy only makes use of pua so as to change
the status of non-presence capable phones, and forwards everything else to the
dedicated presence server, so I've added to my configuration:<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>loadmodule "pua.so"<o:p></o:p></p>
<p class=MsoNormal>loadmodule "pua_mi.so"<o:p></o:p></p>
<p class=MsoNormal>loadmodule "pua_usrloc.so"<o:p></o:p></p>
<p class=MsoNormal>loadmodule "pua_dialoginfo.so"<o:p></o:p></p>
<p class=MsoNormal>loadmodule "presence"<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>modparam("pua", "db_url",
"mysql://user:pass@host/db")<o:p></o:p></p>
<p class=MsoNormal>modparam("pua_usrloc", "default_domain",
"domain.com")<o:p></o:p></p>
<p class=MsoNormal>modparam("pua_usrloc", "10.1.1.1",
"sip:presence@10.1.1.1:5061")<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>modparam("pua_dialoginfo", "10.1.1.1",
"sip:presence@10.1.1.1:5061")<o:p></o:p></p>
<p class=MsoNormal>modparam("pua_dialoginfo",
"include_callid", 1)<o:p></o:p></p>
<p class=MsoNormal>modparam("pua_dialoginfo",
"include_tags", 1)<o:p></o:p></p>
<p class=MsoNormal>modparam("pua_dialoginfo",
"caller_confirmed", 0)<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>modparam("pua_dialoginfo",
"caller_spec_param", "$avp(i:10)")<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>modparam("presence",
"mix_dialog_presence", 1)<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>...<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>if (has_totag()) {<o:p></o:p></p>
<p class=MsoNormal> if
(loose_route()) {<o:p></o:p></p>
<p class=MsoNormal>
if (is_method("BYE")) {<o:p></o:p></p>
<p class=MsoNormal>
setflag(1);<o:p></o:p></p>
<p class=MsoNormal>
setflag(3);<o:p></o:p></p>
<p class=MsoNormal>
} else if (is_method("INVITE")) {<o:p></o:p></p>
<p class=MsoNormal>
record_route();<o:p></o:p></p>
<p class=MsoNormal>
}<o:p></o:p></p>
<p class=MsoNormal>
route(1);<o:p></o:p></p>
<p class=MsoNormal> } else {<o:p></o:p></p>
<p class=MsoNormal>
if( is_method("SUBSCRIBE") && $rd == "10.1.1.1" ) {<o:p></o:p></p>
<p class=MsoNormal>
t_relay("udp:10.1.1.1:5061");<o:p></o:p></p>
<p class=MsoNormal>
exit;<o:p></o:p></p>
<p class=MsoNormal>
}<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>
if ( is_method("ACK") ) {<o:p></o:p></p>
<p class=MsoNormal>
if ( t_check_trans() ) {<o:p></o:p></p>
<p class=MsoNormal>
t_relay();<o:p></o:p></p>
<p class=MsoNormal>
exit;<o:p></o:p></p>
<p class=MsoNormal>
} else {<o:p></o:p></p>
<p class=MsoNormal>
exit;<o:p></o:p></p>
<p class=MsoNormal>
}<o:p></o:p></p>
<p class=MsoNormal>
}<o:p></o:p></p>
<p class=MsoNormal>
<o:p></o:p></p>
<p class=MsoNormal>
sl_send_reply("404","Not here");<o:p></o:p></p>
<p class=MsoNormal> }<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal> exit;<o:p></o:p></p>
<p class=MsoNormal>}<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>...<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>if( is_method("PUBLISH|SUBSCRIBE|NOTIFY")
&& src_ip != 10.1.1.1 ) {<o:p></o:p></p>
<p class=MsoNormal>
t_relay("udp:10.1.1.1:5061");<o:p></o:p></p>
<p class=MsoNormal> exit;<o:p></o:p></p>
<p class=MsoNormal>}<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>...<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>if( !search("^User-Agent: X-Lite")) {<o:p></o:p></p>
<p class=MsoNormal>
pua_set_publish();<o:p></o:p></p>
<p class=MsoNormal>}<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>Presence appears to be partially working, but what seems rather
odd (and I believe is the reason why I am facing problems) is the following:<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>I am seeing on the presence opensips:<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:presence:build_dlg_t: CONTACT =
sip:12345@10.1.1.1:5080;transport=udp<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:tm:t_uac:
next_hop=<sip:10.1.1.1:5080;lr=on;ftag=23294e0b7d1b57e4><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:core:mk_proxy: doing DNS
lookup...<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:tm:dlg2hash: 3372<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:tm:print_request_uri:
sip:12345@10.1.1.1:5080;transport=udp<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:tm:set_timer: relative timeout
is 500000<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:tm:insert_timer_unsafe: [4]:
0x7feff60bba28 (30600000)<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:tm:set_timer: relative timeout
is 30<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:tm:insert_timer_unsafe: [0]:
0x7feff60bba58 (60)<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] INFO:presence:send_notify_request:
NOTIFY sip:12345@domain.com via sip:10.1.1.1:5080;lr=on;ftag=23294e0b7d1b57e4
on behalf of sip:54321@domain.com for event presence<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:tm:t_unref: UNREF_UNSAFE:
[0x7feff60c0428] after is 0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:core:destroy_avp_list:
destroying list (nil)<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:core:receive_msg: cleaning up<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:core:parse_msg: SIP Reply
(status):<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:core:parse_msg: version:
<SIP/2.0><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:core:parse_msg:
status: <404><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [25064] DBG:core:parse_msg:
reason: <Not here><o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>As you can see it mentions "NOTIFY sip:12345@domain.com
via sip:10.1.1.1:5080;". The registrar/proxy on the other side:<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg: SIP Request:<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg:
method: <NOTIFY><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg:
uri: <sip:12345@10.1.1.1:5080;transport=udp><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg: version:
<SIP/2.0><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: flags=2<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_via_param: found
param type 232, <branch> = <z9hG4bKc2d.7df81c95.0>; state=16<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_via: end of header
reached, state=5<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: via found,
flags=2<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: this is the
first via<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:receive_msg: After
parse_msg...<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:receive_msg: preparing to
run routing scripts...<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: flags=100<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to_param:
tag=23294e0b7d1b57e4<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to: end of header
reached, state=29<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to: display={},
ruri={sip:12345@domain.com}<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field: <To>
[54]; uri=[sip:12345@domain.com] <o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field: to body
[<sip:12345@domain.com>]<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field: cseq
<CSeq>: <2> <NOTIFY><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field:
content_length=327<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:maxfwd:is_maxfwd_present: value
= 70 <o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to_param:
tag=2ccb18146f1d6c25cf2547e9412b7255-79b3<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to: end of header
reached, state=29<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to: display={},
ruri={sip:54321@domain.com}<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_new_result: allocate 48
bytes for result set at 0x7e6ca0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns: 3
columns returned from the query<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_allocate_columns:
allocate 84 bytes for result columns at 0x7e4020<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x7e4038)[0]=[value]<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
use DB_STRING result type<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x7e4048)[1]=[attribute]<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
use DB_STRING result type<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x7e4058)[2]=[type]<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
use DB_INT result type<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_convert_rows:
no rows returned from the query<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:avpops:db_close_query: close avp
query<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_free_columns: freeing
result columns at 0x7e4020<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_free_rows: freeing 0
rows<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_free_result: freeing
result set at 0x7e6ca0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:avpops:ops_dbload_avps: loaded
avps = 0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:check_ip_address: params
10.1.1.1, 10.1.1.1, 0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers:
flags=ffffffffffffffff<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field: found end of
header<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:uri:has_totag: totag found<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: flags=200<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:rr:is_preloaded: is_preloaded:
No<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:grep_sock_info: checking if
host==us: 12==12 && [10.1.1.1] == [10.1.1.1]<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:grep_sock_info: checking if
port 5080 matches port 5080<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:rr:after_strict: Next hop:
'sip:10.1.1.1:5080;lr=on;ftag=23294e0b7d1b57e4' is loose router<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers:
flags=ffffffffffffffff<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:rr:after_strict: The last route
URI: 'sip:10.1.1.1:5080;lr=on;ftag=23294e0b7d1b57e4'<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:rr:run_rr_callbacks: callback id
1 entered with <transport=udp><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: flags=48<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:dialog:get_dlg: input
ci=<aa909f8d90deb784@192.168.4.51>(29),
tt=<2ccb18146f1d6c25cf2547e9412b7255-79b3>(37),
ft=<23294e0b7d1b57e4>(16)<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:dialog:get_dlg: no dialog
callid='aa909f8d90deb784@192.168.4.51' found<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:dialog:dlg_onroute: Callid
'aa909f8d90deb784@192.168.4.51' not found<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:rr:run_rr_callbacks: callback id
2 entered with <transport=udp><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:uac:restore_uri: getting 'vsf'
Route param<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:uac:restore_uri: route param
'vsf' not found<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:uac:restore_uri: getting 'vst'
Route param<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:uac:restore_uri: route param 'vst'
not found<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:t_newtran: transaction on
entrance=0xffffffffffffffff<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers:
flags=ffffffffffffffff<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: flags=78<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:t_lookup_request: start
searching: hash=3372, isACK=0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:matching_3261: RFC3261
transaction matching failed<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:t_lookup_request: no
transaction found<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:run_reqin_callbacks:
trans=0x7f5658f8a810, callback type 1, id 2 entered<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:siptrace:trace_onreq_in: trace
off...<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:run_reqin_callbacks:
trans=0x7f5658f8a810, callback type 1, id 1 entered<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:run_reqin_callbacks:
trans=0x7f5658f8a810, callback type 1, id 0 entered<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:_shm_resize: resize(0)
called<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:mk_proxy: doing DNS
lookup...<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:set_timer: relative timeout
is 500000<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:insert_timer_unsafe: [4]:
0x7f5658f8aa30 (47300000)<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:set_timer: relative timeout
is 30<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:insert_timer_unsafe: [0]:
0x7f5658f8aa60 (76)<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:t_relay_to: new transaction
fwd'ed<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:tm:t_unref: UNREF_UNSAFE:
[0x7f5658f8a810] after is 0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:destroy_avp_list:
destroying list (nil)<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:receive_msg: cleaning up<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg: SIP Request:<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg:
method: <NOTIFY><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg:
uri:
<sip:10.1.1.1:5080;lr=on;ftag=23294e0b7d1b57e4><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg: version:
<SIP/2.0><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: flags=2<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_via_param: found
param type 232, <branch> = <z9hG4bKc2d.d64e9016.0>; state=16<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_via: end of header
reached, state=5<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: via found,
flags=2<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: this is the
first via<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:receive_msg: After parse_msg...<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:receive_msg: preparing to
run routing scripts...<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: flags=100<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_via_param: found
param type 235, <rport> = <5061>; state=6<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_via_param: found
param type 234, <received> = <10.1.1.1>; state=6<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_via_param: found
param type 232, <branch> = <z9hG4bKc2d.7df81c95.0>; state=16<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_via: end of header
reached, state=5<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: via found,
flags=100<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers:
parse_headers: this is the second via<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to_param:
tag=23294e0b7d1b57e4<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to: end of header
reached, state=29<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to: display={},
ruri={sip:12345@domain.com}<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field: <To>
[54]; uri=[sip:12345@domain.com] <o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field: to body
[<sip:12345@domain.com>]<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field: cseq
<CSeq>: <2> <NOTIFY><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field:
content_length=327<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:maxfwd:is_maxfwd_present: value
= 69 <o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to_param:
tag=2ccb18146f1d6c25cf2547e9412b7255-79b3<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to: end of header
reached, state=29<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_to: display={},
ruri={sip:54321@domain.com}<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_new_result: allocate 48
bytes for result set at 0x7e6130<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns: 3
columns returned from the query<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_allocate_columns:
allocate 84 bytes for result columns at 0x7e4020<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x7e4038)[0]=[value]<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
use DB_STRING result type<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x7e4048)[1]=[attribute]<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
use DB_STRING result type<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
RES_NAMES(0x7e4058)[2]=[type]<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_get_columns:
use DB_INT result type<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:db_mysql:db_mysql_convert_rows:
no rows returned from the query<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:avpops:db_close_query: close avp
query<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_free_columns: freeing
result columns at 0x7e4020<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_free_rows: freeing 0
rows<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:db_free_result: freeing
result set at 0x7e6130<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:avpops:ops_dbload_avps: loaded
avps = 0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:check_ip_address: params
10.1.1.1, 10.1.1.1, 0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers:
flags=ffffffffffffffff<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:get_hdr_field: found end of
header<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:uri:has_totag: totag found<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers: flags=200<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:rr:find_first_route: No Route
headers found<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:rr:loose_route: There is no
Route HF<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_headers:
flags=ffffffffffffffff<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:check_ip_address: params
10.1.1.1, 10.1.1.1, 0<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:sl:run_sl_callbacks: callback id
0 entered<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:siptrace:trace_sl_onreply_out:
trace off...<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:destroy_avp_list:
destroying list (nil)<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:receive_msg: cleaning up<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg: SIP Reply
(status):<o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg: version:
<SIP/2.0><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg:
status: <404><o:p></o:p></p>
<p class=MsoNormal>Mar 28 16:27:38 [24199] DBG:core:parse_msg:
reason: <Not here><o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>The proxy gets the Via: header from the SIP NOTIFY and
forwards the message once more to himself setting the uri to <a
href="sip:10.1.1.1:5080;lr=on;ftag=23294e0b7d1b57e4">sip:10.1.1.1:5080;lr=on;ftag=23294e0b7d1b57e4</a>.
Then the proxy cannot find the destination thus responding with 404 Not Here.
The 404 message is being redirected once again to the proxy and then back to
the presence service.<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>I am feeling there is something wrong with the domains
configuration on both the Presence/Proxy opensips. <o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>Could anyone share any ideas on the matter?<o:p></o:p></p>
<p class=MsoNormal><o:p> </o:p></p>
<p class=MsoNormal>Regards,<o:p></o:p></p>
<p class=MsoNormal>Paris<o:p></o:p></p>
</div>
</body>
</html>