[SR-Users] Msilo not delivering message (status 408)
Peter Villeneuve
petervnv1 at gmail.com
Wed Jul 16 17:44:05 CEST 2014
And here's the full routing block. I copied it from someone else on the
list that was troubleshooting msilo a while back.
Also, I can confirm the message never gets delivered because it remains in
msilo DB and kamailio logs shows it trying and trying.
####### Routing Logic ########
# Main SIP request routing logic
# - processing of any incoming SIP request starts with this route
route {
# per request initial checks
route(REQINIT);
# NAT detection
route(NAT);
# handle requests within SIP dialogs
route(WITHINDLG);
### only initial requests (no To tag)
# CANCEL processing
if (is_method("CANCEL"))
{
if (t_check_trans())
t_relay();
exit;
}
if (is_method("MESSAGE"))
{
log(1,"-------------------------------------------------------->WE
HAVE A NEW MESSAGE\n");
route(MSILO_MESSAGE);
}
t_check_trans();
# authentication
route(AUTH);
# record routing for dialog forming requests (in case they are routed)
# - remove preloaded route headers
remove_hf("Route");
if (is_method("INVITE|SUBSCRIBE"))
record_route();
# account only INVITEs
if (is_method("INVITE"))
{
setflag(FLT_ACC); # do accounting
}
# dispatch requests to foreign domains
route(SIPOUT);
### requests for my local domains
# handle presence related requests
route(PRESENCE);
# handle registrations
route(REGISTRAR);
if ($rU==$null)
{
# request with no Username in RURI
sl_send_reply("484","Address Incomplete");
exit;
}
# dispatch destinations to PSTN
route(PSTN);
# user location service
route(LOCATION);
route(RELAY);
}
route[RELAY] {
#!ifdef WITH_NAT
if (check_route_param("nat=yes")) {
setbflag(FLB_NATB);
}
if (isflagset(FLT_NATS) || isbflagset(FLB_NATB)) {
route(RTPPROXY);
}
#!endif
/* example how to enable some additional event routes */
if (is_method("INVITE")) {
#t_on_branch("BRANCH_ONE");
t_on_reply("REPLY_ONE");
t_on_failure("FAIL_ONE");
}
if (!t_relay()) {
sl_reply_error();
}
exit;
}
##storing offline messages
route[MSILO_MESSAGE]{
if (lookup("location")){
t_on_failure("FAIL_MESSAGE");
t_relay();
xlog("L_NOTICE","---------------------------------MI: ($mi), MB: ($mb)\n");
}else{
xlog("L_NOTICE","------------------MESSAGE PROCESSING: location not found,
storing offline message ID: ($ru)\n");
m_store("$ru");
sl_send_reply("202", "ACCEPTED");
xlog("L_NOTICE","------------------MESSAGE PROCESSING: location not found,
stored offline message ID: ($ru)\n");
};
exit;
};
failure_route[FAIL_MESSAGE]{
xlog("L_NOTICE","------------------MESSAGE PROCESSING: user cannot be
reached, storing offline message ID: ($ou)\n");
m_store("$ou");
t_reply("202", "ACCEPTED");
xlog("L_NOTICE","------------------MESSAGE PROCESSING: user cannot be
reached, stored offline message ID: ($ou)\n");
exit;
}
# Per SIP request initial checks
route[REQINIT] {
#!ifdef WITH_ANTIFLOOD
# flood dection from same IP and traffic ban for a while
# be sure you exclude checking trusted peers, such as pstn gateways
# - local host excluded (e.g., loop to self)
if(src_ip!=myself)
{
if($sht(ipban=>$si)!=$null)
{
# ip is already blocked
xdbg("request from blocked IP - $rm from $fu (IP:$si:$sp)\n");
exit;
}
if (!pike_check_req())
{
xlog("L_ALERT","ALERT: pike blocking $rm from $fu (IP:$si:$sp)\n");
$sht(ipban=>$si) = 1;
exit;
}
}
#!endif
if (!mf_process_maxfwd_header("10")) {
sl_send_reply("483","Too Many Hops");
exit;
}
if(!sanity_check("1511", "7"))
{
xlog("Malformed SIP message from $si:$sp\n");
exit;
}
}
# Handle requests within SIP dialogs
route[WITHINDLG] {
if (has_totag()) {
# sequential request withing a dialog should
# take the path determined by record-routing
if (loose_route()) {
if (is_method("BYE")) {
setflag(FLT_ACC); # do accounting ...
setflag(FLT_ACCFAILED); # ... even if the transaction fails
}
route(RELAY);
} else {
if (is_method("SUBSCRIBE") && uri == myself) {
# in-dialog subscribe requests
route(PRESENCE);
exit;
}
if ( is_method("ACK") ) {
if ( t_check_trans() ) {
# no loose-route, but stateful ACK;
# must be an ACK after a 487
# or e.g. 404 from upstream server
t_relay();
exit;
} else {
# ACK without matching transaction ... ignore and discard
exit;
}
}
sl_send_reply("404","Not here");
}
exit;
}
}
# Handle SIP registrations
route[REGISTRAR] {
if (is_method("REGISTER"))
{
if(isflagset(FLT_NATS))
{
setbflag(FLB_NATB);
# uncomment next line to do SIP NAT pinging
setbflag(FLB_NATSIPPING);
}
if (!save("location"))
sl_reply_error();
# MSILO - dumping user's offline messages
m_dump();
log(1,"-------------------------MESSAGE PROCESSING:
dumping offline messages\n");
exit;
}
}
# USER location service
route[LOCATION] {
#!ifdef WITH_ALIASDB
# search in DB-based aliases
alias_db_lookup("dbaliases");
#!endif
if (!lookup("location")) {
switch ($rc) {
case -1:
case -3:
t_newtran();
t_reply("404", "Not Found");
exit;
case -2:
sl_send_reply("405", "Method Not Allowed");
exit;
}
}
# when routing via usrloc, log the missed calls also
if (is_method("INVITE"))
{
setflag(FLT_ACCMISSED);
}
}
# Presence server route
route[PRESENCE] {
if(!is_method("PUBLISH|SUBSCRIBE"))
return;
#!ifdef WITH_PRESENCE
if (!t_newtran())
{
sl_reply_error();
exit;
};
if(is_method("PUBLISH"))
{
handle_publish();
t_release();
}
else
if( is_method("SUBSCRIBE"))
{
handle_subscribe();
t_release();
}
exit;
#!endif
# if presence enabled, this part will not be executed
if (is_method("PUBLISH") || $rU==$null)
{
sl_send_reply("404", "Not here");
exit;
}
return;
}
# Authentication route
route[AUTH] {
#!ifdef WITH_AUTH
if (is_method("REGISTER"))
{
# authenticate the REGISTER requests (uncomment to enable auth)
if (!www_authorize("$td", "subscriber"))
{
www_challenge("$td", "0");
exit;
}
if ($au!=$tU)
{
sl_send_reply("403","Forbidden auth ID");
exit;
}
} else {
#!ifdef WITH_IPAUTH
if(allow_source_address())
{
# source IP allowed
return;
}
#!endif
# authenticate if from local subscriber
if (from_uri==myself)
{
if (!proxy_authorize("$fd", "subscriber")) {
proxy_challenge("$fd", "0");
exit;
}
if (is_method("PUBLISH"))
{
if ($au!=$tU) {
sl_send_reply("403","Forbidden auth ID");
exit;
}
} else {
if ($au!=$fU) {
sl_send_reply("403","Forbidden auth ID");
exit;
}
}
consume_credentials();
# caller authenticated
} else {
# caller is not local subscriber, then check if it calls
# a local destination, otherwise deny, not an open relay here
if (!uri==myself)
{
sl_send_reply("403","Not relaying");
exit;
}
}
}
#!endif
return;
}
# Caller NAT detection route
route[NAT] {
#!ifdef WITH_NAT
force_rport();
if (nat_uac_test("19")) {
if (method=="REGISTER") {
fix_nated_register();
} else {
fix_nated_contact();
}
setflag(FLT_NATS);
}
#!endif
return;
}
# RTPProxy control
route[RTPPROXY] {
#!ifdef WITH_NAT
if (is_method("BYE")) {
unforce_rtp_proxy();
} else if (is_method("INVITE")){
rtpproxy_offer();
}
if (!has_totag()) add_rr_param(";nat=yes");
#!endif
return;
}
# Routing to foreign domains
route[SIPOUT] {
if (!uri==myself)
{
append_hf("P-hint: outbound\r\n");
route(RELAY);
}
}
# PSTN GW routing
route[PSTN] {
#!ifdef WITH_PSTN
# check if PSTN GW IP is defined
if (strempty($sel(cfg_get.pstn.gw_ip))) {
xlog("SCRIPT: PSTN rotuing enabled but pstn.gw_ip not defined\n");
return;
}
# route to PSTN dialed numbers starting with '+' or '00'
# (international format)
# - update the condition to match your dialing rules for PSTN routing
if(!($rU=~"^(\+|00)[1-9][0-9]{3,20}$"))
return;
# only local users allowed to call
if(from_uri!=myself) {
sl_send_reply("403", "Not Allowed");
exit;
}
$ru = "sip:" + $rU + "@" + $sel(cfg_get.pstn.gw_ip);
route(RELAY);
exit;
#!endif
return;
}
# XMLRPC routing
#!ifdef WITH_XMLRPC
route[XMLRPC]
{
# allow XMLRPC from localhost
if ((method=="POST" || method=="GET")
&& (src_ip==127.0.0.1)) {
# close connection only for xmlrpclib user agents (there is a bug in
# xmlrpclib: it waits for EOF before interpreting the response).
if ($hdr(User-Agent) =~ "xmlrpclib")
set_reply_close();
set_reply_no_connect();
dispatch_rpc();
exit;
}
send_reply("403", "Forbidden");
exit;
}
#!endif
# Sample branch router
branch_route[BRANCH_ONE] {
xdbg("new branch at $ru\n");
}
# Sample onreply route
onreply_route[REPLY_ONE] {
xdbg("incoming reply\n");
#!ifdef WITH_NAT
if ((isflagset(FLT_NATS) || isbflagset(FLB_NATB))
&& status=~"(183)|(2[0-9][0-9])") {
rtpproxy_answer();
}
if (isbflagset("6")) {
fix_nated_contact();
}
#!endif
}
# Sample failure route
failure_route[FAIL_ONE] {
#!ifdef WITH_NAT
if (is_method("INVITE")
&& (isbflagset(FLB_NATB) || isflagset(FLT_NATS))) {
unforce_rtp_proxy();
}
#!endif
if (t_is_canceled()) {
exit;
}
# uncomment the following lines if you want to block client
# redirect based on 3xx replies.
##if (t_check_status("3[0-9][0-9]")) {
##t_reply("404","Not found");
## exit;
##}
# uncomment the following lines if you want to redirect the failed
# calls to a different new destination
##if (t_check_status("486|408")) {
## sethostport("192.168.2.100:5060");
## append_branch();
## # do not set the missed call flag again
## t_relay();
##}
}
On Wed, Jul 16, 2014 at 4:38 PM, Peter Villeneuve <petervnv1 at gmail.com>
wrote:
> Thanks for your help Daniel.
>
> OK I ngrepped port 5060 and indeed the message tries to get delivered.
> However it never arrives at the client (1010 in this case).
> I have added localhost listeners for UDP and TCP, even though all clients
> register through TLS only (could this be the root of the problem?). I added
> localhost listeners because I was getting the no socket found error message.
>
> Listening on
> udp: 127.0.0.1:5080
> tcp: 127.0.0.1:5060
> tls: 79.my.server.IP1:5061
> Aliases:
> tcp: localhost:5060
> udp: localhost:5080
> *: my.domain.com:*
>
> U 2014/07/16 15:29:14.904285 127.0.0.1:5080 -> 79.my.server.IP1:5060
> MESSAGE sip:1010 at my.domain.com SIP/2.0.
> Via: SIP/2.0/UDP 127.0.0.1:5080
> ;branch=z9hG4bKcde3.542219f4000000000000000000000000.0.
> To: <sip:1010 at my.domain.com>.
> From: <sip:1000 at my.domain.com>;tag=def4124455da8a0b8e97eafabd028e26-2c30.
> CSeq: 10 MESSAGE.
> Call-ID: 16b126996f51d89c-12460 at 127.0.0.1.
> Max-Forwards: 70.
> Content-Length: 58.
> User-Agent: kamailio (4.1.4 (x86_64/linux)).
> Date: Wed, 16 Jul 2014 15:22:31 GMT.
> Content-Type: text/plain.
> .
> [Offline message - Wed Jul 16 15:22:31 2014] Test 1,2,3
>
>
> On Wed, Jul 16, 2014 at 8:58 AM, Daniel-Constantin Mierla <
> miconda at gmail.com> wrote:
>
>> Hello,
>>
>> can you watch the sip traffic on the network to see if the sip messages
>> is sent out? You can use ngrep, like:
>>
>> ngrep -d any -qt -W byline "sip" port 5060
>>
>> Cheers,
>> Daniel
>>
>>
>> On 14/07/14 19:56, Peter Villeneuve wrote:
>>
>> Hi,
>>
>> This is most likely a stupid mistake on my route config, but msilo
>> correctly stores messages for offline users, but when they come back
>> online, the message never arrives.
>>
>> Looking through the debug logs indeed there is an error, with status
>> 408, which suggests kamaiio can't find a route to the user that just
>> logged. Before I spend hours looking through all the routing blocks,
>> perhaps someone more exprienced in kamailio can see the problem right away
>> and offer a solution?
>>
>> Thanks
>>
>>
>> DEBUG: msilo [msilo.c:1148]: m_dump(): msg [1-12] for:
>> sip:1010 at my.domain.com
>> DEBUG: <core> [io_wait.h:617]: io_watch_del(): DBG: io_watch_del
>> (0x8e3040, 9, -1, 0x10) fd_no=2 called
>> DEBUG: <core> [tcp_read.c:1437]: release_tcpconn(): releasing con
>> 0x7f61ced1b1b0, state 1, fd=9, id=1
>> DEBUG: <core> [tcp_read.c:1438]: release_tcpconn(): extra_data
>> 0x7f61ced1c4f8
>> DEBUG: <core> [tcp_main.c:3385]: handle_tcp_child(): handle_tcp_child:
>> reader response= 7f61ced1b1b0, 1 from 0
>> DEBUG: <core> [io_wait.h:390]: io_watch_add(): DBG:
>> io_watch_add(0x89ec40, 26, 2, 0x7f61ced1b1b0), fd_no=21
>> DEBUG: <core> [tcp_main.c:3515]: handle_tcp_child(): handle_tcp_child:
>> CONN_RELEASE 0x7f61ced1b1b0 refcnt= 1
>> DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>>
>> T_code=0, new_code=408
>> DEBUG: tm [t_reply.c:2086]: local_reply(): DEBUG: local_reply: branch=0,
>> save=0, winner=0
>> DEBUG: tm [t_reply.c:2123]: local_reply(): DEBUG: local transaction
>> completed
>> DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG:
>> trans=0x7f61ced1d868, callback type 1024, id 0 entered
>> DEBUG: msilo [msilo.c:1317]: m_tm_callback(): *completed with status
>> 408* [mid: 140058058335472/13]
>> DEBUG: msilo [msilo.c:1325]: m_tm_callback(): message <13> was not sent
>> successfully
>> DEBUG: msilo [ms_msg_list.c:236]: msg_list_set_flag(): mid:13 fl:8
>> DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>>
>> T_code=0, new_code=408
>> DEBUG: tm [t_reply.c:2086]: local_reply(): DEBUG: local_reply: branch=0,
>> save=0, winner=0
>> DEBUG: tm [t_reply.c:2123]: local_reply(): DEBUG: local transaction
>> completed
>> DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG:
>> trans=0x7f61ced5f098, callback type 1024, id 0 entered
>> DEBUG: msilo [msilo.c:1317]: m_tm_callback(): completed with status 408
>> [mid: 140058058329184/12]
>> DEBUG: msilo [msilo.c:1325]: m_tm_callback(): message <12> was not sent
>> successfully
>> DEBUG: msilo [ms_msg_list.c:236]: msg_list_set_flag(): mid:12 fl:8
>>
>>
>>
>>
>> _______________________________________________
>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing listsr-users at lists.sip-router.orghttp://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>
>>
>> --
>> Daniel-Constantin Mierla - http://www.asipto.comhttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
>>
>>
>> _______________________________________________
>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
>> sr-users at lists.sip-router.org
>> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>
>>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20140716/f6b71150/attachment.html>
More information about the sr-users
mailing list